{"level":"warn","ts":"2026-09-07T18:22:55.327601Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-09-07T18:22:55.327765Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.14:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://162.253.55.14:2380","--initial-cluster=instance=https://162.253.55.14:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.14:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.14: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-09-07T18:22:55.327881Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-09-07T18:22:55.327901Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.14:2380"]} {"level":"info","ts":"2026-09-07T18:22:55.327926Z","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-09-07T18:22:55.328943Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.14:2379"]} {"level":"info","ts":"2026-09-07T18:22:55.32909Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":16,"max-cpu-available":16,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://162.253.55.14:2380"],"listen-peer-urls":["https://162.253.55.14:2380"],"advertise-client-urls":["https://162.253.55.14:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.14:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.14: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-09-07T18:22:55.334985Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.538193ms"} {"level":"info","ts":"2026-09-07T18:22:55.346529Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"5db0a1b394e67b72","cluster-id":"cfda6ccfe21bda40"} {"level":"info","ts":"2026-09-07T18:22:55.34665Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5db0a1b394e67b72 switched to configuration voters=()"} {"level":"info","ts":"2026-09-07T18:22:55.346679Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5db0a1b394e67b72 became follower at term 0"} {"level":"info","ts":"2026-09-07T18:22:55.346699Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 5db0a1b394e67b72 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-09-07T18:22:55.346712Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5db0a1b394e67b72 became follower at term 1"} {"level":"info","ts":"2026-09-07T18:22:55.346767Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5db0a1b394e67b72 switched to configuration voters=(6751073634097724274)"} {"level":"warn","ts":"2026-09-07T18:22:55.370409Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-09-07T18:22:55.372623Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-09-07T18:22:55.374342Z","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-09-07T18:22:55.378136Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"5db0a1b394e67b72","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-09-07T18:22:55.37853Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"5db0a1b394e67b72","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-09-07T18:22:55.378598Z","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-09-07T18:22:55.378878Z","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-09-07T18:22:55.378899Z","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-09-07T18:22:55.380314Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5db0a1b394e67b72 switched to configuration voters=(6751073634097724274)"} {"level":"info","ts":"2026-09-07T18:22:55.380609Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"cfda6ccfe21bda40","local-member-id":"5db0a1b394e67b72","added-peer-id":"5db0a1b394e67b72","added-peer-peer-urls":["https://162.253.55.14:2380"]} {"level":"info","ts":"2026-09-07T18:22:55.383401Z","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-09-07T18:22:55.383544Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.14:2380"} {"level":"info","ts":"2026-09-07T18:22:55.383592Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.14:2380"} {"level":"info","ts":"2026-09-07T18:22:55.383729Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"5db0a1b394e67b72","initial-advertise-peer-urls":["https://162.253.55.14:2380"],"listen-peer-urls":["https://162.253.55.14:2380"],"advertise-client-urls":["https://162.253.55.14:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.14:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-09-07T18:22:55.383765Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-09-07T18:22:55.54741Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5db0a1b394e67b72 is starting a new election at term 1"} {"level":"info","ts":"2026-09-07T18:22:55.547483Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5db0a1b394e67b72 became pre-candidate at term 1"} {"level":"info","ts":"2026-09-07T18:22:55.54751Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5db0a1b394e67b72 received MsgPreVoteResp from 5db0a1b394e67b72 at term 1"} {"level":"info","ts":"2026-09-07T18:22:55.547546Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5db0a1b394e67b72 became candidate at term 2"} {"level":"info","ts":"2026-09-07T18:22:55.547562Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5db0a1b394e67b72 received MsgVoteResp from 5db0a1b394e67b72 at term 2"} {"level":"info","ts":"2026-09-07T18:22:55.547577Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5db0a1b394e67b72 became leader at term 2"} {"level":"info","ts":"2026-09-07T18:22:55.547595Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 5db0a1b394e67b72 elected leader 5db0a1b394e67b72 at term 2"} {"level":"info","ts":"2026-09-07T18:22:55.548551Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"5db0a1b394e67b72","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.14:2379]}","request-path":"/0/members/5db0a1b394e67b72/attributes","cluster-id":"cfda6ccfe21bda40","publish-timeout":"7s"} {"level":"info","ts":"2026-09-07T18:22:55.548701Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-07T18:22:55.548776Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-07T18:22:55.548909Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-09-07T18:22:55.548972Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-09-07T18:22:55.549077Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-09-07T18:22:55.550688Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"cfda6ccfe21bda40","local-member-id":"5db0a1b394e67b72","cluster-version":"3.5"} {"level":"info","ts":"2026-09-07T18:22:55.55081Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-09-07T18:22:55.550857Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-09-07T18:22:55.552238Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.14:2379"} {"level":"info","ts":"2026-09-07T18:22:55.552331Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-09-07T18:23:05.930832Z","caller":"traceutil/trace.go:171","msg":"trace[1179527405] transaction","detail":"{read_only:false; response_revision:348; number_of_response:1; }","duration":"105.663269ms","start":"2026-09-07T18:23:05.825136Z","end":"2026-09-07T18:23:05.930799Z","steps":["trace[1179527405] 'process raft request' (duration: 78.461026ms)","trace[1179527405] 'compare' (duration: 27.049118ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T18:23:16.314348Z","caller":"traceutil/trace.go:171","msg":"trace[573608672] transaction","detail":"{read_only:false; response_revision:386; number_of_response:1; }","duration":"161.162425ms","start":"2026-09-07T18:23:16.153154Z","end":"2026-09-07T18:23:16.314317Z","steps":["trace[573608672] 'process raft request' (duration: 160.906937ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T18:23:16.504657Z","caller":"traceutil/trace.go:171","msg":"trace[1250945592] transaction","detail":"{read_only:false; response_revision:387; number_of_response:1; }","duration":"186.055989ms","start":"2026-09-07T18:23:16.318568Z","end":"2026-09-07T18:23:16.504624Z","steps":["trace[1250945592] 'process raft request' (duration: 185.006938ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T18:23:21.874242Z","caller":"traceutil/trace.go:171","msg":"trace[1404630713] transaction","detail":"{read_only:false; response_revision:429; number_of_response:1; }","duration":"140.899878ms","start":"2026-09-07T18:23:21.733318Z","end":"2026-09-07T18:23:21.874218Z","steps":["trace[1404630713] 'process raft request' (duration: 140.741393ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T18:23:42.198992Z","caller":"traceutil/trace.go:171","msg":"trace[1748059550] transaction","detail":"{read_only:false; response_revision:600; number_of_response:1; }","duration":"115.599224ms","start":"2026-09-07T18:23:42.083357Z","end":"2026-09-07T18:23:42.198956Z","steps":["trace[1748059550] 'process raft request' (duration: 115.011896ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T18:23:42.659532Z","caller":"traceutil/trace.go:171","msg":"trace[623639836] transaction","detail":"{read_only:false; response_revision:602; number_of_response:1; }","duration":"314.743638ms","start":"2026-09-07T18:23:42.344763Z","end":"2026-09-07T18:23:42.659507Z","steps":["trace[623639836] 'process raft request' (duration: 314.582053ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T18:23:42.65996Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T18:23:42.344728Z","time spent":"314.874151ms","remote":"127.0.0.1:51602","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17130,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-07T18:23:42.680708Z","caller":"traceutil/trace.go:171","msg":"trace[968155310] linearizableReadLoop","detail":"{readStateIndex:630; appliedIndex:628; }","duration":"229.198159ms","start":"2026-09-07T18:23:42.451452Z","end":"2026-09-07T18:23:42.68065Z","steps":["trace[968155310] 'read index received' (duration: 207.990475ms)","trace[968155310] 'applied index is now lower than readState.Index' (duration: 21.206504ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T18:23:42.681148Z","caller":"traceutil/trace.go:171","msg":"trace[1094383070] transaction","detail":"{read_only:false; response_revision:603; number_of_response:1; }","duration":"291.416271ms","start":"2026-09-07T18:23:42.389714Z","end":"2026-09-07T18:23:42.681131Z","steps":["trace[1094383070] 'process raft request' (duration: 290.761192ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T18:23:42.681448Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.009983ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-09-07T18:23:42.681536Z","caller":"traceutil/trace.go:171","msg":"trace[1099528649] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:603; }","duration":"230.175337ms","start":"2026-09-07T18:23:42.45134Z","end":"2026-09-07T18:23:42.681515Z","steps":["trace[1099528649] 'agreement among raft nodes before linearized reading' (duration: 230.024013ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T18:23:43.417105Z","caller":"traceutil/trace.go:171","msg":"trace[1912197718] linearizableReadLoop","detail":"{readStateIndex:633; appliedIndex:632; }","duration":"428.976401ms","start":"2026-09-07T18:23:42.9881Z","end":"2026-09-07T18:23:43.417076Z","steps":["trace[1912197718] 'read index received' (duration: 428.053534ms)","trace[1912197718] 'applied index is now lower than readState.Index' (duration: 921.817µs)"],"step_count":2} {"level":"info","ts":"2026-09-07T18:23:43.41718Z","caller":"traceutil/trace.go:171","msg":"trace[345391182] transaction","detail":"{read_only:false; response_revision:606; number_of_response:1; }","duration":"657.088849ms","start":"2026-09-07T18:23:42.760063Z","end":"2026-09-07T18:23:43.417151Z","steps":["trace[345391182] 'process raft request' (duration: 656.410188ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T18:23:43.417252Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"429.134695ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-07T18:23:43.417298Z","caller":"traceutil/trace.go:171","msg":"trace[1934406741] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:606; }","duration":"429.262229ms","start":"2026-09-07T18:23:42.988021Z","end":"2026-09-07T18:23:43.417283Z","steps":["trace[1934406741] 'agreement among raft nodes before linearized reading' (duration: 429.160226ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T18:23:43.417319Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T18:23:42.760028Z","time spent":"657.209152ms","remote":"127.0.0.1:51532","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036289,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-07T18:23:43.417336Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T18:23:42.987988Z","time spent":"429.338392ms","remote":"127.0.0.1:51442","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-09-07T18:24:01.11741Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.873055ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-09-07T18:24:01.117609Z","caller":"traceutil/trace.go:171","msg":"trace[952034738] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:776; }","duration":"110.158733ms","start":"2026-09-07T18:24:01.007428Z","end":"2026-09-07T18:24:01.117587Z","steps":["trace[952034738] 'range keys from in-memory index tree' (duration: 109.637798ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T18:24:01.307643Z","caller":"traceutil/trace.go:171","msg":"trace[459206351] transaction","detail":"{read_only:false; response_revision:777; number_of_response:1; }","duration":"186.034048ms","start":"2026-09-07T18:24:01.121571Z","end":"2026-09-07T18:24:01.307605Z","steps":["trace[459206351] 'process raft request' (duration: 185.813761ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T18:24:30.005909Z","caller":"traceutil/trace.go:171","msg":"trace[442505761] transaction","detail":"{read_only:false; response_revision:1173; number_of_response:1; }","duration":"268.779098ms","start":"2026-09-07T18:24:29.737105Z","end":"2026-09-07T18:24:30.005884Z","steps":["trace[442505761] 'process raft request' (duration: 268.608623ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T18:24:30.006082Z","caller":"traceutil/trace.go:171","msg":"trace[569613348] linearizableReadLoop","detail":"{readStateIndex:1213; appliedIndex:1213; }","duration":"252.743378ms","start":"2026-09-07T18:24:29.753307Z","end":"2026-09-07T18:24:30.00605Z","steps":["trace[569613348] 'read index received' (duration: 252.732258ms)","trace[569613348] 'applied index is now lower than readState.Index' (duration: 8.45µs)"],"step_count":2} {"level":"warn","ts":"2026-09-07T18:24:30.00631Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.992605ms","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-09-07T18:24:30.006443Z","caller":"traceutil/trace.go:171","msg":"trace[549329459] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1173; }","duration":"253.125999ms","start":"2026-09-07T18:24:29.753251Z","end":"2026-09-07T18:24:30.006377Z","steps":["trace[549329459] 'agreement among raft nodes before linearized reading' (duration: 252.894202ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T18:24:30.007223Z","caller":"traceutil/trace.go:171","msg":"trace[1419810828] transaction","detail":"{read_only:false; response_revision:1174; number_of_response:1; }","duration":"129.887227ms","start":"2026-09-07T18:24:29.87732Z","end":"2026-09-07T18:24:30.007208Z","steps":["trace[1419810828] 'process raft request' (duration: 129.808344ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T18:25:20.352114Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.67749ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-07T18:25:20.352407Z","caller":"traceutil/trace.go:171","msg":"trace[909654390] transaction","detail":"{read_only:false; response_revision:1851; number_of_response:1; }","duration":"141.236319ms","start":"2026-09-07T18:25:20.211123Z","end":"2026-09-07T18:25:20.352359Z","steps":["trace[909654390] 'process raft request' (duration: 39.069165ms)","trace[909654390] 'compare' (duration: 101.545276ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T18:25:20.652399Z","caller":"traceutil/trace.go:171","msg":"trace[481192810] linearizableReadLoop","detail":"{readStateIndex:1929; appliedIndex:1928; }","duration":"285.92162ms","start":"2026-09-07T18:25:20.366453Z","end":"2026-09-07T18:25:20.652374Z","steps":["trace[481192810] 'read index received' (duration: 244.381922ms)","trace[481192810] 'applied index is now lower than readState.Index' (duration: 41.538648ms)"],"step_count":2} {"level":"warn","ts":"2026-09-07T18:25:20.652551Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.079644ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-07T18:25:20.652678Z","caller":"traceutil/trace.go:171","msg":"trace[602954787] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:1852; }","duration":"286.235899ms","start":"2026-09-07T18:25:20.366419Z","end":"2026-09-07T18:25:20.652654Z","steps":["trace[602954787] 'agreement among raft nodes before linearized reading' (duration: 286.067744ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T18:25:20.652588Z","caller":"traceutil/trace.go:171","msg":"trace[2128271432] transaction","detail":"{read_only:false; response_revision:1852; number_of_response:1; }","duration":"295.751528ms","start":"2026-09-07T18:25:20.356726Z","end":"2026-09-07T18:25:20.652478Z","steps":["trace[2128271432] 'process raft request' (duration: 254.121397ms)","trace[2128271432] 'compare' (duration: 41.398204ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T18:25:21.037849Z","caller":"traceutil/trace.go:171","msg":"trace[2100918288] transaction","detail":"{read_only:false; response_revision:1854; number_of_response:1; }","duration":"170.561568ms","start":"2026-09-07T18:25:20.867267Z","end":"2026-09-07T18:25:21.037829Z","steps":["trace[2100918288] 'process raft request' (duration: 170.441294ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T18:26:05.94899Z","caller":"traceutil/trace.go:171","msg":"trace[1776053258] transaction","detail":"{read_only:false; response_revision:2154; number_of_response:1; }","duration":"108.835741ms","start":"2026-09-07T18:26:05.84013Z","end":"2026-09-07T18:26:05.948966Z","steps":["trace[1776053258] 'process raft request' (duration: 108.666656ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T18:26:06.255765Z","caller":"traceutil/trace.go:171","msg":"trace[986539153] transaction","detail":"{read_only:false; response_revision:2156; number_of_response:1; }","duration":"101.908379ms","start":"2026-09-07T18:26:06.153831Z","end":"2026-09-07T18:26:06.25574Z","steps":["trace[986539153] 'process raft request' (duration: 101.741293ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T18:26:06.276287Z","caller":"traceutil/trace.go:171","msg":"trace[303033501] transaction","detail":"{read_only:false; response_revision:2157; number_of_response:1; }","duration":"118.301298ms","start":"2026-09-07T18:26:06.157954Z","end":"2026-09-07T18:26:06.276256Z","steps":["trace[303033501] 'process raft request' (duration: 118.110102ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T18:26:06.276333Z","caller":"traceutil/trace.go:171","msg":"trace[568498656] transaction","detail":"{read_only:false; response_revision:2158; number_of_response:1; }","duration":"117.01809ms","start":"2026-09-07T18:26:06.159287Z","end":"2026-09-07T18:26:06.276305Z","steps":["trace[568498656] 'process raft request' (duration: 116.902797ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T18:27:53.097404Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.59357ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/valkey-node-0.18d31d4139860d34\" ","response":"range_response_count:1 size:765"} {"level":"info","ts":"2026-09-07T18:27:53.097543Z","caller":"traceutil/trace.go:171","msg":"trace[1661191169] range","detail":"{range_begin:/registry/events/openstack/valkey-node-0.18d31d4139860d34; range_end:; response_count:1; response_revision:2923; }","duration":"123.802995ms","start":"2026-09-07T18:27:52.973713Z","end":"2026-09-07T18:27:53.097516Z","steps":["trace[1661191169] 'range keys from in-memory index tree' (duration: 123.430095ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T18:27:53.097649Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.531768ms","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-09-07T18:27:53.097643Z","caller":"traceutil/trace.go:171","msg":"trace[1505774982] transaction","detail":"{read_only:false; response_revision:2924; number_of_response:1; }","duration":"121.333544ms","start":"2026-09-07T18:27:52.976271Z","end":"2026-09-07T18:27:53.097604Z","steps":["trace[1505774982] 'process raft request' (duration: 58.416407ms)","trace[1505774982] 'compare' (duration: 62.705871ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T18:27:53.097768Z","caller":"traceutil/trace.go:171","msg":"trace[208481992] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2923; }","duration":"185.704803ms","start":"2026-09-07T18:27:52.912016Z","end":"2026-09-07T18:27:53.097721Z","steps":["trace[208481992] 'range keys from in-memory index tree' (duration: 185.229999ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T18:27:53.097898Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.978713ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-07T18:27:53.098011Z","caller":"traceutil/trace.go:171","msg":"trace[986672495] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2924; }","duration":"109.112687ms","start":"2026-09-07T18:27:52.98887Z","end":"2026-09-07T18:27:53.097983Z","steps":["trace[986672495] 'agreement among raft nodes before linearized reading' (duration: 108.838789ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T18:27:53.097624Z","caller":"traceutil/trace.go:171","msg":"trace[1427169477] linearizableReadLoop","detail":"{readStateIndex:3039; appliedIndex:3038; }","duration":"108.687565ms","start":"2026-09-07T18:27:52.988896Z","end":"2026-09-07T18:27:53.097583Z","steps":["trace[1427169477] 'read index received' (duration: 45.737626ms)","trace[1427169477] 'applied index is now lower than readState.Index' (duration: 62.948309ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T18:31:21.628174Z","caller":"traceutil/trace.go:171","msg":"trace[553577925] transaction","detail":"{read_only:false; response_revision:4217; number_of_response:1; }","duration":"114.19562ms","start":"2026-09-07T18:31:21.51395Z","end":"2026-09-07T18:31:21.628146Z","steps":["trace[553577925] 'process raft request' (duration: 114.027015ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T18:31:21.840221Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.217565ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-07T18:31:21.840521Z","caller":"traceutil/trace.go:171","msg":"trace[2066603749] transaction","detail":"{read_only:false; response_revision:4218; number_of_response:1; }","duration":"272.13942ms","start":"2026-09-07T18:31:21.568262Z","end":"2026-09-07T18:31:21.840401Z","steps":["trace[2066603749] 'process raft request' (duration: 97.627036ms)","trace[2066603749] 'compare' (duration: 173.922907ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T18:31:21.840563Z","caller":"traceutil/trace.go:171","msg":"trace[658027456] transaction","detail":"{read_only:false; response_revision:4219; number_of_response:1; }","duration":"242.313178ms","start":"2026-09-07T18:31:21.59822Z","end":"2026-09-07T18:31:21.840533Z","steps":["trace[658027456] 'process raft request' (duration: 242.120852ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T18:31:21.881276Z","caller":"traceutil/trace.go:171","msg":"trace[1994616410] transaction","detail":"{read_only:false; response_revision:4220; number_of_response:1; }","duration":"154.87531ms","start":"2026-09-07T18:31:21.726373Z","end":"2026-09-07T18:31:21.881248Z","steps":["trace[1994616410] 'process raft request' (duration: 154.651163ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T18:32:44.441587Z","caller":"traceutil/trace.go:171","msg":"trace[1236803925] transaction","detail":"{read_only:false; response_revision:5134; number_of_response:1; }","duration":"175.191694ms","start":"2026-09-07T18:32:44.266349Z","end":"2026-09-07T18:32:44.44154Z","steps":["trace[1236803925] 'process raft request' (duration: 93.609068ms)","trace[1236803925] 'compare' (duration: 81.253687ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T18:32:44.459304Z","caller":"traceutil/trace.go:171","msg":"trace[638637435] transaction","detail":"{read_only:false; response_revision:5135; number_of_response:1; }","duration":"191.216073ms","start":"2026-09-07T18:32:44.268049Z","end":"2026-09-07T18:32:44.459266Z","steps":["trace[638637435] 'process raft request' (duration: 190.971505ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T18:32:44.459302Z","caller":"traceutil/trace.go:171","msg":"trace[508387712] transaction","detail":"{read_only:false; response_revision:5136; number_of_response:1; }","duration":"189.948016ms","start":"2026-09-07T18:32:44.269343Z","end":"2026-09-07T18:32:44.459291Z","steps":["trace[508387712] 'process raft request' (duration: 189.788401ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T18:32:44.459491Z","caller":"traceutil/trace.go:171","msg":"trace[331328660] linearizableReadLoop","detail":"{readStateIndex:5337; appliedIndex:5334; }","duration":"189.677599ms","start":"2026-09-07T18:32:44.26978Z","end":"2026-09-07T18:32:44.459457Z","steps":["trace[331328660] 'read index received' (duration: 90.198529ms)","trace[331328660] 'applied index is now lower than readState.Index' (duration: 99.477209ms)"],"step_count":2} {"level":"warn","ts":"2026-09-07T18:32:44.459772Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.468878ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/monitoring/alertmanager-kube-prometheus-stack-alertmanager\" ","response":"range_response_count:1 size:11980"} {"level":"warn","ts":"2026-09-07T18:32:44.459791Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.962827ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-07T18:32:44.45987Z","caller":"traceutil/trace.go:171","msg":"trace[1781079302] range","detail":"{range_begin:/registry/statefulsets/monitoring/alertmanager-kube-prometheus-stack-alertmanager; range_end:; response_count:1; response_revision:5137; }","duration":"181.574842ms","start":"2026-09-07T18:32:44.278238Z","end":"2026-09-07T18:32:44.459813Z","steps":["trace[1781079302] 'agreement among raft nodes before linearized reading' (duration: 181.437198ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T18:32:44.459944Z","caller":"traceutil/trace.go:171","msg":"trace[1283312087] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:0; response_revision:5137; }","duration":"190.120692ms","start":"2026-09-07T18:32:44.269757Z","end":"2026-09-07T18:32:44.459878Z","steps":["trace[1283312087] 'agreement among raft nodes before linearized reading' (duration: 189.889125ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T18:32:44.460083Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.92001ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/\" range_end:\"/registry/pods/monitoring0\" ","response":"range_response_count:9 size:61968"} {"level":"info","ts":"2026-09-07T18:32:44.460157Z","caller":"traceutil/trace.go:171","msg":"trace[1398187036] range","detail":"{range_begin:/registry/pods/monitoring/; range_end:/registry/pods/monitoring0; response_count:9; response_revision:5137; }","duration":"184.029542ms","start":"2026-09-07T18:32:44.276112Z","end":"2026-09-07T18:32:44.460141Z","steps":["trace[1398187036] 'agreement among raft nodes before linearized reading' (duration: 183.630701ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T18:32:44.459609Z","caller":"traceutil/trace.go:171","msg":"trace[336926613] transaction","detail":"{read_only:false; response_revision:5137; number_of_response:1; }","duration":"179.485169ms","start":"2026-09-07T18:32:44.280109Z","end":"2026-09-07T18:32:44.459595Z","steps":["trace[336926613] 'process raft request' (duration: 179.118229ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T18:32:44.460254Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.916709ms","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-09-07T18:32:44.46036Z","caller":"traceutil/trace.go:171","msg":"trace[1033040132] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5137; }","duration":"117.102205ms","start":"2026-09-07T18:32:44.343233Z","end":"2026-09-07T18:32:44.460335Z","steps":["trace[1033040132] 'agreement among raft nodes before linearized reading' (duration: 116.59554ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T18:32:44.460438Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.250155ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-58665b12-dfb9-4338-84c7-42866122a2cb\" ","response":"range_response_count:1 size:2708"} {"level":"info","ts":"2026-09-07T18:32:44.460508Z","caller":"traceutil/trace.go:171","msg":"trace[1828982261] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-58665b12-dfb9-4338-84c7-42866122a2cb; range_end:; response_count:1; response_revision:5137; }","duration":"189.39132ms","start":"2026-09-07T18:32:44.2711Z","end":"2026-09-07T18:32:44.460492Z","steps":["trace[1828982261] 'agreement among raft nodes before linearized reading' (duration: 188.527834ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T18:32:50.483917Z","caller":"traceutil/trace.go:171","msg":"trace[817332334] transaction","detail":"{read_only:false; response_revision:5294; number_of_response:1; }","duration":"170.091635ms","start":"2026-09-07T18:32:50.313794Z","end":"2026-09-07T18:32:50.483885Z","steps":["trace[817332334] 'process raft request' (duration: 111.741469ms)","trace[817332334] 'compare' (duration: 58.195621ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T18:32:50.488117Z","caller":"traceutil/trace.go:171","msg":"trace[1237688704] linearizableReadLoop","detail":"{readStateIndex:5502; appliedIndex:5500; }","duration":"137.944985ms","start":"2026-09-07T18:32:50.350124Z","end":"2026-09-07T18:32:50.488069Z","steps":["trace[1237688704] 'read index received' (duration: 75.420457ms)","trace[1237688704] 'applied index is now lower than readState.Index' (duration: 62.523538ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T18:32:50.488147Z","caller":"traceutil/trace.go:171","msg":"trace[968971656] transaction","detail":"{read_only:false; response_revision:5295; number_of_response:1; }","duration":"167.601483ms","start":"2026-09-07T18:32:50.320524Z","end":"2026-09-07T18:32:50.488126Z","steps":["trace[968971656] 'process raft request' (duration: 167.353474ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T18:32:50.488473Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.310056ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:1935"} {"level":"info","ts":"2026-09-07T18:32:50.488584Z","caller":"traceutil/trace.go:171","msg":"trace[1503899730] 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:5295; }","duration":"138.45987ms","start":"2026-09-07T18:32:50.350096Z","end":"2026-09-07T18:32:50.488556Z","steps":["trace[1503899730] 'agreement among raft nodes before linearized reading' (duration: 138.09923ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T18:32:53.285107Z","caller":"traceutil/trace.go:171","msg":"trace[2132278121] linearizableReadLoop","detail":"{readStateIndex:5625; appliedIndex:5624; }","duration":"142.623352ms","start":"2026-09-07T18:32:53.142454Z","end":"2026-09-07T18:32:53.285078Z","steps":["trace[2132278121] 'read index received' (duration: 142.378725ms)","trace[2132278121] 'applied index is now lower than readState.Index' (duration: 242.707µs)"],"step_count":2} {"level":"info","ts":"2026-09-07T18:32:53.285185Z","caller":"traceutil/trace.go:171","msg":"trace[1288853399] transaction","detail":"{read_only:false; response_revision:5416; number_of_response:1; }","duration":"146.395851ms","start":"2026-09-07T18:32:53.138726Z","end":"2026-09-07T18:32:53.285122Z","steps":["trace[1288853399] 'process raft request' (duration: 146.141714ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T18:32:53.285315Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.849128ms","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-09-07T18:32:53.285358Z","caller":"traceutil/trace.go:171","msg":"trace[871590802] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5416; }","duration":"142.982582ms","start":"2026-09-07T18:32:53.142364Z","end":"2026-09-07T18:32:53.285347Z","steps":["trace[871590802] 'agreement among raft nodes before linearized reading' (duration: 142.818197ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T18:32:53.538779Z","caller":"traceutil/trace.go:171","msg":"trace[800389204] linearizableReadLoop","detail":"{readStateIndex:5627; appliedIndex:5626; }","duration":"147.997258ms","start":"2026-09-07T18:32:53.39076Z","end":"2026-09-07T18:32:53.538757Z","steps":["trace[800389204] 'read index received' (duration: 147.849174ms)","trace[800389204] 'applied index is now lower than readState.Index' (duration: 146.884µs)"],"step_count":2} {"level":"info","ts":"2026-09-07T18:32:53.538835Z","caller":"traceutil/trace.go:171","msg":"trace[131516645] transaction","detail":"{read_only:false; response_revision:5418; number_of_response:1; }","duration":"188.140943ms","start":"2026-09-07T18:32:53.350649Z","end":"2026-09-07T18:32:53.53879Z","steps":["trace[131516645] 'process raft request' (duration: 187.938107ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T18:32:53.53895Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.161053ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-07T18:32:53.538985Z","caller":"traceutil/trace.go:171","msg":"trace[101269148] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/; range_end:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0; response_count:0; response_revision:5418; }","duration":"148.255046ms","start":"2026-09-07T18:32:53.390719Z","end":"2026-09-07T18:32:53.538974Z","steps":["trace[101269148] 'agreement among raft nodes before linearized reading' (duration: 148.158423ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T18:32:55.839361Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2941} {"level":"info","ts":"2026-09-07T18:32:55.95555Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2941,"took":"113.870361ms","hash":4167020156,"current-db-size-bytes":53743616,"current-db-size":"54 MB","current-db-size-in-use-bytes":29741056,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-09-07T18:32:55.955944Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4167020156,"revision":2941,"compact-revision":-1} {"level":"info","ts":"2026-09-07T18:33:08.068415Z","caller":"traceutil/trace.go:171","msg":"trace[2077481705] transaction","detail":"{read_only:false; response_revision:5688; number_of_response:1; }","duration":"145.857622ms","start":"2026-09-07T18:33:07.92248Z","end":"2026-09-07T18:33:08.068337Z","steps":["trace[2077481705] 'process raft request' (duration: 145.615045ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T18:33:09.552686Z","caller":"traceutil/trace.go:171","msg":"trace[393538973] linearizableReadLoop","detail":"{readStateIndex:5923; appliedIndex:5922; }","duration":"201.361774ms","start":"2026-09-07T18:33:09.351287Z","end":"2026-09-07T18:33:09.552649Z","steps":["trace[393538973] 'read index received' (duration: 201.185038ms)","trace[393538973] 'applied index is now lower than readState.Index' (duration: 174.806µs)"],"step_count":2} {"level":"warn","ts":"2026-09-07T18:33:09.553073Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.727294ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-09-07T18:33:09.553138Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.673752ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-09-07T18:33:09.553194Z","caller":"traceutil/trace.go:171","msg":"trace[1267284255] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5703; }","duration":"201.93565ms","start":"2026-09-07T18:33:09.351233Z","end":"2026-09-07T18:33:09.553169Z","steps":["trace[1267284255] 'agreement among raft nodes before linearized reading' (duration: 201.549659ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T18:33:09.553206Z","caller":"traceutil/trace.go:171","msg":"trace[416241184] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5703; }","duration":"164.819486ms","start":"2026-09-07T18:33:09.388367Z","end":"2026-09-07T18:33:09.553187Z","steps":["trace[416241184] 'agreement among raft nodes before linearized reading' (duration: 164.504507ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T18:33:09.804587Z","caller":"traceutil/trace.go:171","msg":"trace[1415074541] transaction","detail":"{read_only:false; response_revision:5704; number_of_response:1; }","duration":"250.497979ms","start":"2026-09-07T18:33:09.554058Z","end":"2026-09-07T18:33:09.804556Z","steps":["trace[1415074541] 'process raft request' (duration: 166.66043ms)","trace[1415074541] 'compare' (duration: 83.705305ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T18:33:09.828811Z","caller":"traceutil/trace.go:171","msg":"trace[212525677] transaction","detail":"{read_only:false; response_revision:5705; number_of_response:1; }","duration":"270.288868ms","start":"2026-09-07T18:33:09.558481Z","end":"2026-09-07T18:33:09.82877Z","steps":["trace[212525677] 'process raft request' (duration: 269.937798ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T18:33:09.828832Z","caller":"traceutil/trace.go:171","msg":"trace[1043226081] transaction","detail":"{read_only:false; response_revision:5706; number_of_response:1; }","duration":"268.952948ms","start":"2026-09-07T18:33:09.559839Z","end":"2026-09-07T18:33:09.828792Z","steps":["trace[1043226081] 'process raft request' (duration: 268.814964ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T18:33:09.829307Z","caller":"traceutil/trace.go:171","msg":"trace[886069942] linearizableReadLoop","detail":"{readStateIndex:5927; appliedIndex:5923; }","duration":"110.007413ms","start":"2026-09-07T18:33:09.719283Z","end":"2026-09-07T18:33:09.82929Z","steps":["trace[886069942] 'read index received' (duration: 1.451082ms)","trace[886069942] 'applied index is now lower than readState.Index' (duration: 108.554231ms)"],"step_count":2} {"level":"warn","ts":"2026-09-07T18:33:09.829548Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.245661ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-b4z7c\" ","response":"range_response_count:1 size:6520"} {"level":"info","ts":"2026-09-07T18:33:09.829594Z","caller":"traceutil/trace.go:171","msg":"trace[1130394525] range","detail":"{range_begin:/registry/pods/openstack/keepalived-b4z7c; range_end:; response_count:1; response_revision:5707; }","duration":"110.379435ms","start":"2026-09-07T18:33:09.719199Z","end":"2026-09-07T18:33:09.829578Z","steps":["trace[1130394525] 'agreement among raft nodes before linearized reading' (duration: 110.186159ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T18:33:09.829531Z","caller":"traceutil/trace.go:171","msg":"trace[1591028776] transaction","detail":"{read_only:false; response_revision:5707; number_of_response:1; }","duration":"265.078475ms","start":"2026-09-07T18:33:09.564397Z","end":"2026-09-07T18:33:09.829475Z","steps":["trace[1591028776] 'process raft request' (duration: 264.309933ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T18:33:17.400756Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.339254ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12215"} {"level":"info","ts":"2026-09-07T18:33:17.400881Z","caller":"traceutil/trace.go:171","msg":"trace[801024604] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:5875; }","duration":"110.54512ms","start":"2026-09-07T18:33:17.29031Z","end":"2026-09-07T18:33:17.400855Z","steps":["trace[801024604] 'range keys from in-memory index tree' (duration: 110.076007ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T18:33:17.743461Z","caller":"traceutil/trace.go:171","msg":"trace[1608173729] transaction","detail":"{read_only:false; response_revision:5876; number_of_response:1; }","duration":"106.850072ms","start":"2026-09-07T18:33:17.636555Z","end":"2026-09-07T18:33:17.743405Z","steps":["trace[1608173729] 'process raft request' (duration: 106.577824ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T18:33:29.016296Z","caller":"traceutil/trace.go:171","msg":"trace[256080764] transaction","detail":"{read_only:false; response_revision:6016; number_of_response:1; }","duration":"106.841862ms","start":"2026-09-07T18:33:28.909388Z","end":"2026-09-07T18:33:29.01623Z","steps":["trace[256080764] 'process raft request' (duration: 85.114467ms)","trace[256080764] 'compare' (duration: 21.349764ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T18:33:29.016368Z","caller":"traceutil/trace.go:171","msg":"trace[465764079] transaction","detail":"{read_only:false; response_revision:6018; number_of_response:1; }","duration":"105.824713ms","start":"2026-09-07T18:33:28.910489Z","end":"2026-09-07T18:33:29.016313Z","steps":["trace[465764079] 'process raft request' (duration: 105.72881ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T18:33:29.016363Z","caller":"traceutil/trace.go:171","msg":"trace[1022881726] transaction","detail":"{read_only:false; response_revision:6017; number_of_response:1; }","duration":"106.857483ms","start":"2026-09-07T18:33:28.909459Z","end":"2026-09-07T18:33:29.016316Z","steps":["trace[1022881726] 'process raft request' (duration: 106.624506ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T18:37:55.846311Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5458} {"level":"info","ts":"2026-09-07T18:37:55.937015Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5458,"took":"88.851747ms","hash":3861863055,"current-db-size-bytes":53743616,"current-db-size":"54 MB","current-db-size-in-use-bytes":28643328,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-09-07T18:37:55.93709Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3861863055,"revision":5458,"compact-revision":2941} {"level":"info","ts":"2026-09-07T18:39:33.732816Z","caller":"traceutil/trace.go:171","msg":"trace[506365332] linearizableReadLoop","detail":"{readStateIndex:9058; appliedIndex:9057; }","duration":"211.145766ms","start":"2026-09-07T18:39:33.521647Z","end":"2026-09-07T18:39:33.732792Z","steps":["trace[506365332] 'read index received' (duration: 210.91872ms)","trace[506365332] 'applied index is now lower than readState.Index' (duration: 226.196µs)"],"step_count":2} {"level":"warn","ts":"2026-09-07T18:39:33.732984Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.312112ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-07T18:39:33.733021Z","caller":"traceutil/trace.go:171","msg":"trace[1499797220] range","detail":"{range_begin:/registry/volumeattachments/; range_end:/registry/volumeattachments0; response_count:0; response_revision:8746; }","duration":"211.403714ms","start":"2026-09-07T18:39:33.521609Z","end":"2026-09-07T18:39:33.733012Z","steps":["trace[1499797220] 'agreement among raft nodes before linearized reading' (duration: 211.287ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T18:39:33.733297Z","caller":"traceutil/trace.go:171","msg":"trace[8150967] transaction","detail":"{read_only:false; response_revision:8746; number_of_response:1; }","duration":"360.131357ms","start":"2026-09-07T18:39:33.373098Z","end":"2026-09-07T18:39:33.73323Z","steps":["trace[8150967] 'process raft request' (duration: 359.5411ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T18:39:33.733607Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T18:39:33.373077Z","time spent":"360.382644ms","remote":"127.0.0.1:51672","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-07T18:39:46.582355Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.592896ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-07T18:39:46.582563Z","caller":"traceutil/trace.go:171","msg":"trace[1075114642] linearizableReadLoop","detail":"{readStateIndex:9131; appliedIndex:9130; }","duration":"232.864637ms","start":"2026-09-07T18:39:46.349673Z","end":"2026-09-07T18:39:46.582538Z","steps":["trace[1075114642] 'read index received' (duration: 35.631µs)","trace[1075114642] 'applied index is now lower than readState.Index' (duration: 232.827306ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T18:39:46.582604Z","caller":"traceutil/trace.go:171","msg":"trace[2066281421] transaction","detail":"{read_only:false; response_revision:8816; number_of_response:1; }","duration":"378.389066ms","start":"2026-09-07T18:39:46.20417Z","end":"2026-09-07T18:39:46.582559Z","steps":["trace[2066281421] 'process raft request' (duration: 116.446609ms)","trace[2066281421] 'compare' (duration: 261.433712ms)"],"step_count":2} {"level":"warn","ts":"2026-09-07T18:39:46.582795Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.118986ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-b4z7c\" ","response":"range_response_count:1 size:6520"} {"level":"info","ts":"2026-09-07T18:39:46.582831Z","caller":"traceutil/trace.go:171","msg":"trace[1368381386] range","detail":"{range_begin:/registry/pods/openstack/keepalived-b4z7c; range_end:; response_count:1; response_revision:8816; }","duration":"233.186588ms","start":"2026-09-07T18:39:46.349631Z","end":"2026-09-07T18:39:46.582817Z","steps":["trace[1368381386] 'agreement among raft nodes before linearized reading' (duration: 233.022913ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T18:39:46.582846Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T18:39:46.20412Z","time spent":"378.614613ms","remote":"127.0.0.1:51672","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-07T18:40:15.535403Z","caller":"traceutil/trace.go:171","msg":"trace[750283370] transaction","detail":"{read_only:false; response_revision:9025; number_of_response:1; }","duration":"290.635674ms","start":"2026-09-07T18:40:15.24472Z","end":"2026-09-07T18:40:15.535355Z","steps":["trace[750283370] 'process raft request' (duration: 290.062506ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T18:40:17.999488Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.309971ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-07T18:40:17.999637Z","caller":"traceutil/trace.go:171","msg":"trace[2078762820] transaction","detail":"{read_only:false; response_revision:9035; number_of_response:1; }","duration":"186.741936ms","start":"2026-09-07T18:40:17.812863Z","end":"2026-09-07T18:40:17.999604Z","steps":["trace[2078762820] 'process raft request' (duration: 17.173868ms)","trace[2078762820] 'compare' (duration: 169.139645ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T18:40:18.072778Z","caller":"traceutil/trace.go:171","msg":"trace[281827949] transaction","detail":"{read_only:false; response_revision:9036; number_of_response:1; }","duration":"102.050144ms","start":"2026-09-07T18:40:17.9707Z","end":"2026-09-07T18:40:18.072751Z","steps":["trace[281827949] 'process raft request' (duration: 101.863708ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T18:40:18.074996Z","caller":"traceutil/trace.go:171","msg":"trace[1834108997] transaction","detail":"{read_only:false; response_revision:9037; number_of_response:1; }","duration":"103.835187ms","start":"2026-09-07T18:40:17.971114Z","end":"2026-09-07T18:40:18.074949Z","steps":["trace[1834108997] 'process raft request' (duration: 103.463565ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T18:40:24.234041Z","caller":"traceutil/trace.go:171","msg":"trace[464256323] linearizableReadLoop","detail":"{readStateIndex:9395; appliedIndex:9394; }","duration":"118.670625ms","start":"2026-09-07T18:40:24.115337Z","end":"2026-09-07T18:40:24.234008Z","steps":["trace[464256323] 'read index received' (duration: 118.389236ms)","trace[464256323] 'applied index is now lower than readState.Index' (duration: 280.019µs)"],"step_count":2} {"level":"info","ts":"2026-09-07T18:40:24.234151Z","caller":"traceutil/trace.go:171","msg":"trace[1055673127] transaction","detail":"{read_only:false; response_revision:9070; number_of_response:1; }","duration":"119.395937ms","start":"2026-09-07T18:40:24.1147Z","end":"2026-09-07T18:40:24.234096Z","steps":["trace[1055673127] 'process raft request' (duration: 119.073537ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T18:40:24.234304Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.931693ms","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-09-07T18:40:24.234356Z","caller":"traceutil/trace.go:171","msg":"trace[1405832899] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:9070; }","duration":"119.053476ms","start":"2026-09-07T18:40:24.115287Z","end":"2026-09-07T18:40:24.23434Z","steps":["trace[1405832899] 'agreement among raft nodes before linearized reading' (duration: 118.843ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T18:40:24.27433Z","caller":"traceutil/trace.go:171","msg":"trace[155439385] transaction","detail":"{read_only:false; response_revision:9072; number_of_response:1; }","duration":"158.293676ms","start":"2026-09-07T18:40:24.115997Z","end":"2026-09-07T18:40:24.274291Z","steps":["trace[155439385] 'process raft request' (duration: 158.230234ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T18:40:24.274656Z","caller":"traceutil/trace.go:171","msg":"trace[2001551763] transaction","detail":"{read_only:false; response_revision:9071; number_of_response:1; }","duration":"159.171122ms","start":"2026-09-07T18:40:24.11544Z","end":"2026-09-07T18:40:24.274612Z","steps":["trace[2001551763] 'process raft request' (duration: 158.580354ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T18:40:24.439837Z","caller":"traceutil/trace.go:171","msg":"trace[1471959047] transaction","detail":"{read_only:false; response_revision:9073; number_of_response:1; }","duration":"199.751309ms","start":"2026-09-07T18:40:24.240033Z","end":"2026-09-07T18:40:24.439784Z","steps":["trace[1471959047] 'process raft request' (duration: 162.15952ms)","trace[1471959047] 'compare' (duration: 37.386983ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T18:40:24.445195Z","caller":"traceutil/trace.go:171","msg":"trace[1648502310] linearizableReadLoop","detail":"{readStateIndex:9399; appliedIndex:9397; }","duration":"157.021308ms","start":"2026-09-07T18:40:24.288134Z","end":"2026-09-07T18:40:24.445155Z","steps":["trace[1648502310] 'read index received' (duration: 114.124602ms)","trace[1648502310] 'applied index is now lower than readState.Index' (duration: 42.894986ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T18:40:24.445262Z","caller":"traceutil/trace.go:171","msg":"trace[1154206533] transaction","detail":"{read_only:false; response_revision:9074; number_of_response:1; }","duration":"167.794505ms","start":"2026-09-07T18:40:24.277427Z","end":"2026-09-07T18:40:24.445221Z","steps":["trace[1154206533] 'process raft request' (duration: 167.524437ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T18:40:24.445497Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.321938ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-09-07T18:40:24.445544Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.031517ms","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-09-07T18:40:24.445578Z","caller":"traceutil/trace.go:171","msg":"trace[290580694] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:9074; }","duration":"157.475022ms","start":"2026-09-07T18:40:24.288083Z","end":"2026-09-07T18:40:24.445558Z","steps":["trace[290580694] 'agreement among raft nodes before linearized reading' (duration: 157.173972ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T18:40:24.44559Z","caller":"traceutil/trace.go:171","msg":"trace[1684795366] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:9074; }","duration":"139.211812ms","start":"2026-09-07T18:40:24.306364Z","end":"2026-09-07T18:40:24.445576Z","steps":["trace[1684795366] 'agreement among raft nodes before linearized reading' (duration: 138.969214ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T18:40:24.445633Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.533585ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-09-07T18:40:24.445736Z","caller":"traceutil/trace.go:171","msg":"trace[339846088] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:9074; }","duration":"132.670889ms","start":"2026-09-07T18:40:24.313038Z","end":"2026-09-07T18:40:24.445709Z","steps":["trace[339846088] 'agreement among raft nodes before linearized reading' (duration: 132.401061ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T18:40:24.927474Z","caller":"traceutil/trace.go:171","msg":"trace[1985533596] transaction","detail":"{read_only:false; response_revision:9078; number_of_response:1; }","duration":"132.767832ms","start":"2026-09-07T18:40:24.794668Z","end":"2026-09-07T18:40:24.927435Z","steps":["trace[1985533596] 'process raft request' (duration: 132.539456ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T18:40:25.22893Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.814065ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/issuers/\" range_end:\"/registry/cert-manager.io/issuers0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-07T18:40:25.229033Z","caller":"traceutil/trace.go:171","msg":"trace[1292766218] range","detail":"{range_begin:/registry/cert-manager.io/issuers/; range_end:/registry/cert-manager.io/issuers0; response_count:0; response_revision:9078; }","duration":"169.998961ms","start":"2026-09-07T18:40:25.059012Z","end":"2026-09-07T18:40:25.229011Z","steps":["trace[1292766218] 'count revisions from in-memory index tree' (duration: 169.677892ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T18:40:30.38096Z","caller":"traceutil/trace.go:171","msg":"trace[2023532390] transaction","detail":"{read_only:false; response_revision:9113; number_of_response:1; }","duration":"126.885788ms","start":"2026-09-07T18:40:30.254049Z","end":"2026-09-07T18:40:30.380935Z","steps":["trace[2023532390] 'process raft request' (duration: 126.725594ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T18:40:30.710783Z","caller":"traceutil/trace.go:171","msg":"trace[2132485632] transaction","detail":"{read_only:false; response_revision:9116; number_of_response:1; }","duration":"187.2625ms","start":"2026-09-07T18:40:30.523489Z","end":"2026-09-07T18:40:30.710752Z","steps":["trace[2132485632] 'process raft request' (duration: 187.076325ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T18:40:30.710928Z","caller":"traceutil/trace.go:171","msg":"trace[1212469374] linearizableReadLoop","detail":"{readStateIndex:9443; appliedIndex:9443; }","duration":"184.135858ms","start":"2026-09-07T18:40:30.526741Z","end":"2026-09-07T18:40:30.710876Z","steps":["trace[1212469374] 'read index received' (duration: 184.114057ms)","trace[1212469374] 'applied index is now lower than readState.Index' (duration: 18.501µs)"],"step_count":2} {"level":"warn","ts":"2026-09-07T18:40:30.711357Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.587552ms","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-09-07T18:40:30.711456Z","caller":"traceutil/trace.go:171","msg":"trace[98439515] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:9116; }","duration":"184.715726ms","start":"2026-09-07T18:40:30.526713Z","end":"2026-09-07T18:40:30.711428Z","steps":["trace[98439515] 'agreement among raft nodes before linearized reading' (duration: 184.307503ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T18:40:30.787468Z","caller":"traceutil/trace.go:171","msg":"trace[315018157] transaction","detail":"{read_only:false; response_revision:9117; number_of_response:1; }","duration":"172.163255ms","start":"2026-09-07T18:40:30.615268Z","end":"2026-09-07T18:40:30.787431Z","steps":["trace[315018157] 'process raft request' (duration: 105.424674ms)","trace[315018157] 'compare' (duration: 66.270208ms)"],"step_count":2} {"level":"warn","ts":"2026-09-07T18:40:30.787911Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.432926ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-09-07T18:40:30.788055Z","caller":"traceutil/trace.go:171","msg":"trace[1116714115] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:9118; }","duration":"251.649252ms","start":"2026-09-07T18:40:30.53637Z","end":"2026-09-07T18:40:30.788019Z","steps":["trace[1116714115] 'agreement among raft nodes before linearized reading' (duration: 251.146418ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T18:40:30.788061Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.662389ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rook-ceph-detect-version-x7ck4\" ","response":"range_response_count:1 size:4647"} {"level":"info","ts":"2026-09-07T18:40:30.788142Z","caller":"traceutil/trace.go:171","msg":"trace[2052487283] range","detail":"{range_begin:/registry/pods/openstack/rook-ceph-detect-version-x7ck4; range_end:; response_count:1; response_revision:9118; }","duration":"173.799904ms","start":"2026-09-07T18:40:30.614321Z","end":"2026-09-07T18:40:30.788121Z","steps":["trace[2052487283] 'agreement among raft nodes before linearized reading' (duration: 173.591367ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T18:40:30.788295Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.626914ms","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-09-07T18:40:30.788363Z","caller":"traceutil/trace.go:171","msg":"trace[91411741] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:9118; }","duration":"249.737087ms","start":"2026-09-07T18:40:30.53861Z","end":"2026-09-07T18:40:30.788348Z","steps":["trace[91411741] 'agreement among raft nodes before linearized reading' (duration: 249.49195ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T18:40:46.824123Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-0000000000002595.wal"} {"level":"info","ts":"2026-09-07T18:41:29.099308Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"5db0a1b394e67b72","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-09-07T18:41:29.111335Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-09-07T18:41:29.11147Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-09-07T18:42:55.855228Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":7957} {"level":"info","ts":"2026-09-07T18:42:55.960074Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":7957,"took":"101.404382ms","hash":857090782,"current-db-size-bytes":53743616,"current-db-size":"54 MB","current-db-size-in-use-bytes":25481216,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-09-07T18:42:55.960155Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":857090782,"revision":7957,"compact-revision":5458} {"level":"warn","ts":"2026-09-07T18:47:36.478763Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.854186ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-b4z7c\" ","response":"range_response_count:1 size:6520"} {"level":"info","ts":"2026-09-07T18:47:36.478853Z","caller":"traceutil/trace.go:171","msg":"trace[1189855427] range","detail":"{range_begin:/registry/pods/openstack/keepalived-b4z7c; range_end:; response_count:1; response_revision:12381; }","duration":"123.99154ms","start":"2026-09-07T18:47:36.354843Z","end":"2026-09-07T18:47:36.478835Z","steps":["trace[1189855427] 'range keys from in-memory index tree' (duration: 123.586748ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T18:47:55.862919Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10375} {"level":"info","ts":"2026-09-07T18:47:55.952943Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10375,"took":"86.779162ms","hash":3119310119,"current-db-size-bytes":53743616,"current-db-size":"54 MB","current-db-size-in-use-bytes":25726976,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-09-07T18:47:55.953025Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3119310119,"revision":10375,"compact-revision":7957} {"level":"info","ts":"2026-09-07T18:52:55.870373Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12538} {"level":"info","ts":"2026-09-07T18:52:55.957831Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12538,"took":"84.970867ms","hash":2793082874,"current-db-size-bytes":53743616,"current-db-size":"54 MB","current-db-size-in-use-bytes":27279360,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-09-07T18:52:55.957901Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2793082874,"revision":12538,"compact-revision":10375} {"level":"info","ts":"2026-09-07T18:53:35.787743Z","caller":"traceutil/trace.go:171","msg":"trace[2002516556] transaction","detail":"{read_only:false; response_revision:14827; number_of_response:1; }","duration":"172.043446ms","start":"2026-09-07T18:53:35.615682Z","end":"2026-09-07T18:53:35.787726Z","steps":["trace[2002516556] 'process raft request' (duration: 169.379718ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T18:53:35.787844Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.559742ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephclusters/\" range_end:\"/registry/ceph.rook.io/cephclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-07T18:53:35.787732Z","caller":"traceutil/trace.go:171","msg":"trace[1065116195] linearizableReadLoop","detail":"{readStateIndex:15344; appliedIndex:15343; }","duration":"158.424618ms","start":"2026-09-07T18:53:35.629257Z","end":"2026-09-07T18:53:35.787681Z","steps":["trace[1065116195] 'read index received' (duration: 155.76008ms)","trace[1065116195] 'applied index is now lower than readState.Index' (duration: 2.663168ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T18:53:35.787901Z","caller":"traceutil/trace.go:171","msg":"trace[1363691049] range","detail":"{range_begin:/registry/ceph.rook.io/cephclusters/; range_end:/registry/ceph.rook.io/cephclusters0; response_count:0; response_revision:14827; }","duration":"158.634585ms","start":"2026-09-07T18:53:35.629251Z","end":"2026-09-07T18:53:35.787885Z","steps":["trace[1363691049] 'agreement among raft nodes before linearized reading' (duration: 158.508311ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T18:53:56.536704Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.415261ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-07T18:53:56.536796Z","caller":"traceutil/trace.go:171","msg":"trace[294699353] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:14933; }","duration":"196.569886ms","start":"2026-09-07T18:53:56.340206Z","end":"2026-09-07T18:53:56.536776Z","steps":["trace[294699353] 'count revisions from in-memory index tree' (duration: 196.308907ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T18:54:14.927165Z","caller":"traceutil/trace.go:171","msg":"trace[1730195030] linearizableReadLoop","detail":"{readStateIndex:15567; appliedIndex:15566; }","duration":"329.897938ms","start":"2026-09-07T18:54:14.597243Z","end":"2026-09-07T18:54:14.92714Z","steps":["trace[1730195030] 'read index received' (duration: 329.850446ms)","trace[1730195030] 'applied index is now lower than readState.Index' (duration: 46.702µs)"],"step_count":2} {"level":"info","ts":"2026-09-07T18:54:14.927251Z","caller":"traceutil/trace.go:171","msg":"trace[674928892] transaction","detail":"{read_only:false; response_revision:15041; number_of_response:1; }","duration":"395.033286ms","start":"2026-09-07T18:54:14.53218Z","end":"2026-09-07T18:54:14.927213Z","steps":["trace[674928892] 'process raft request' (duration: 394.83067ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T18:54:14.927346Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"330.071543ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-07T18:54:14.927389Z","caller":"traceutil/trace.go:171","msg":"trace[1293827991] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:15041; }","duration":"330.171726ms","start":"2026-09-07T18:54:14.597205Z","end":"2026-09-07T18:54:14.927377Z","steps":["trace[1293827991] 'agreement among raft nodes before linearized reading' (duration: 330.080743ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T18:54:14.927423Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T18:54:14.532157Z","time spent":"395.171819ms","remote":"127.0.0.1:51672","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-07T18:54:14.92743Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T18:54:14.59719Z","time spent":"330.229048ms","remote":"127.0.0.1:41014","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true "} {"level":"warn","ts":"2026-09-07T18:54:14.931243Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"330.879448ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-07T18:54:14.93135Z","caller":"traceutil/trace.go:171","msg":"trace[1589683070] range","detail":"{range_begin:/registry/networkpolicies/; range_end:/registry/networkpolicies0; response_count:0; response_revision:15041; }","duration":"331.035852ms","start":"2026-09-07T18:54:14.600291Z","end":"2026-09-07T18:54:14.931327Z","steps":["trace[1589683070] 'agreement among raft nodes before linearized reading' (duration: 330.842306ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T18:54:14.931418Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T18:54:14.60028Z","time spent":"331.125774ms","remote":"127.0.0.1:51692","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":4,"response size":30,"request content":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true "} {"level":"warn","ts":"2026-09-07T18:54:14.931374Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.194856ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/openvswitch-l9zdd\" ","response":"range_response_count:1 size:12360"} {"level":"info","ts":"2026-09-07T18:54:14.931521Z","caller":"traceutil/trace.go:171","msg":"trace[818773048] range","detail":"{range_begin:/registry/pods/openstack/openvswitch-l9zdd; range_end:; response_count:1; response_revision:15041; }","duration":"291.467164ms","start":"2026-09-07T18:54:14.640036Z","end":"2026-09-07T18:54:14.931503Z","steps":["trace[818773048] 'agreement among raft nodes before linearized reading' (duration: 291.121134ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T18:54:24.991633Z","caller":"traceutil/trace.go:171","msg":"trace[1025566144] transaction","detail":"{read_only:false; response_revision:15212; number_of_response:1; }","duration":"351.584394ms","start":"2026-09-07T18:54:24.640027Z","end":"2026-09-07T18:54:24.991611Z","steps":["trace[1025566144] 'process raft request' (duration: 351.415919ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T18:54:24.991779Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T18:54:24.63999Z","time spent":"351.725358ms","remote":"127.0.0.1:51510","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":854,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-07T18:54:24.996608Z","caller":"traceutil/trace.go:171","msg":"trace[383234337] linearizableReadLoop","detail":"{readStateIndex:15742; appliedIndex:15740; }","duration":"232.970861ms","start":"2026-09-07T18:54:24.763607Z","end":"2026-09-07T18:54:24.996577Z","steps":["trace[383234337] 'read index received' (duration: 227.883862ms)","trace[383234337] 'applied index is now lower than readState.Index' (duration: 5.084929ms)"],"step_count":2} {"level":"warn","ts":"2026-09-07T18:54:24.996771Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.140516ms","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-09-07T18:54:24.99685Z","caller":"traceutil/trace.go:171","msg":"trace[1080513862] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectstoreusers/; range_end:/registry/ceph.rook.io/cephobjectstoreusers0; response_count:0; response_revision:15212; }","duration":"233.232799ms","start":"2026-09-07T18:54:24.7636Z","end":"2026-09-07T18:54:24.996832Z","steps":["trace[1080513862] 'agreement among raft nodes before linearized reading' (duration: 233.097165ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T18:54:24.997042Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.863926ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-d83a5eec-274b-4e3b-95f2-80202c80b04b\" ","response":"range_response_count:1 size:3606"} {"level":"info","ts":"2026-09-07T18:54:24.997123Z","caller":"traceutil/trace.go:171","msg":"trace[1281121042] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-d83a5eec-274b-4e3b-95f2-80202c80b04b; range_end:; response_count:1; response_revision:15212; }","duration":"151.972999ms","start":"2026-09-07T18:54:24.84513Z","end":"2026-09-07T18:54:24.997103Z","steps":["trace[1281121042] 'agreement among raft nodes before linearized reading' (duration: 151.743463ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T18:54:24.997573Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.191118ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/\" range_end:\"/registry/rabbitmq.com/rabbitmqclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-07T18:54:24.997663Z","caller":"traceutil/trace.go:171","msg":"trace[897457464] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/; range_end:/registry/rabbitmq.com/rabbitmqclusters0; response_count:0; response_revision:15212; }","duration":"192.022043ms","start":"2026-09-07T18:54:24.80559Z","end":"2026-09-07T18:54:24.997612Z","steps":["trace[897457464] 'agreement among raft nodes before linearized reading' (duration: 191.157007ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T18:56:08.131833Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.344267ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-09-07T18:56:08.131866Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.573664ms","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-09-07T18:56:08.131924Z","caller":"traceutil/trace.go:171","msg":"trace[2017019177] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:16312; }","duration":"117.546582ms","start":"2026-09-07T18:56:08.014359Z","end":"2026-09-07T18:56:08.131906Z","steps":["trace[2017019177] 'range keys from in-memory index tree' (duration: 117.154222ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T18:56:08.131959Z","caller":"traceutil/trace.go:171","msg":"trace[1738210580] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:16312; }","duration":"103.706947ms","start":"2026-09-07T18:56:08.028228Z","end":"2026-09-07T18:56:08.131935Z","steps":["trace[1738210580] 'range keys from in-memory index tree' (duration: 103.322786ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T18:56:08.461748Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.570588ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-07T18:56:08.46198Z","caller":"traceutil/trace.go:171","msg":"trace[697540020] transaction","detail":"{read_only:false; response_revision:16318; number_of_response:1; }","duration":"216.042097ms","start":"2026-09-07T18:56:08.245918Z","end":"2026-09-07T18:56:08.46196Z","steps":["trace[697540020] 'process raft request' (duration: 215.948904ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T18:56:08.461978Z","caller":"traceutil/trace.go:171","msg":"trace[687459975] transaction","detail":"{read_only:false; response_revision:16317; number_of_response:1; }","duration":"263.106816ms","start":"2026-09-07T18:56:08.198839Z","end":"2026-09-07T18:56:08.461945Z","steps":["trace[687459975] 'process raft request' (duration: 52.236409ms)","trace[687459975] 'compare' (duration: 210.237527ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T18:56:08.555098Z","caller":"traceutil/trace.go:171","msg":"trace[1355695501] linearizableReadLoop","detail":"{readStateIndex:16882; appliedIndex:16879; }","duration":"278.357281ms","start":"2026-09-07T18:56:08.276711Z","end":"2026-09-07T18:56:08.555068Z","steps":["trace[1355695501] 'read index received' (duration: 114.571005ms)","trace[1355695501] 'applied index is now lower than readState.Index' (duration: 163.785026ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T18:56:08.555173Z","caller":"traceutil/trace.go:171","msg":"trace[1736776112] transaction","detail":"{read_only:false; response_revision:16319; number_of_response:1; }","duration":"302.027675ms","start":"2026-09-07T18:56:08.253121Z","end":"2026-09-07T18:56:08.555149Z","steps":["trace[1736776112] 'process raft request' (duration: 301.782668ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T18:56:08.555384Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T18:56:08.253093Z","time spent":"302.1694ms","remote":"127.0.0.1:51672","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-07T18:56:08.555558Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.845357ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-metadata\" ","response":"range_response_count:1 size:682"} {"level":"info","ts":"2026-09-07T18:56:08.555607Z","caller":"traceutil/trace.go:171","msg":"trace[1444042547] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-metadata; range_end:; response_count:1; response_revision:16319; }","duration":"278.898458ms","start":"2026-09-07T18:56:08.276695Z","end":"2026-09-07T18:56:08.555594Z","steps":["trace[1444042547] 'agreement among raft nodes before linearized reading' (duration: 278.499276ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T18:56:08.600996Z","caller":"traceutil/trace.go:171","msg":"trace[1496239420] transaction","detail":"{read_only:false; response_revision:16320; number_of_response:1; }","duration":"171.967757ms","start":"2026-09-07T18:56:08.428994Z","end":"2026-09-07T18:56:08.600962Z","steps":["trace[1496239420] 'process raft request' (duration: 144.481803ms)","trace[1496239420] 'compare' (duration: 27.277158ms)"],"step_count":2} {"level":"warn","ts":"2026-09-07T18:56:08.60106Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"315.241793ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5523"} {"level":"warn","ts":"2026-09-07T18:56:08.601101Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"324.286196ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/neutron-server\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-07T18:56:08.601082Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"324.226315ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-07T18:56:08.601129Z","caller":"traceutil/trace.go:171","msg":"trace[410534573] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:16320; }","duration":"315.396047ms","start":"2026-09-07T18:56:08.285717Z","end":"2026-09-07T18:56:08.601113Z","steps":["trace[410534573] 'agreement among raft nodes before linearized reading' (duration: 315.176041ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T18:56:08.601138Z","caller":"traceutil/trace.go:171","msg":"trace[1631638397] range","detail":"{range_begin:/registry/services/endpoints/openstack/neutron-server; range_end:; response_count:0; response_revision:16320; }","duration":"324.352968ms","start":"2026-09-07T18:56:08.276772Z","end":"2026-09-07T18:56:08.601125Z","steps":["trace[1631638397] 'agreement among raft nodes before linearized reading' (duration: 324.309337ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T18:56:08.601157Z","caller":"traceutil/trace.go:171","msg":"trace[1487208343] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:16320; }","duration":"324.324428ms","start":"2026-09-07T18:56:08.276814Z","end":"2026-09-07T18:56:08.601138Z","steps":["trace[1487208343] 'agreement among raft nodes before linearized reading' (duration: 324.183024ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T18:56:08.601174Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T18:56:08.276753Z","time spent":"324.41193ms","remote":"127.0.0.1:51592","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":0,"response size":28,"request content":"key:\"/registry/services/endpoints/openstack/neutron-server\" "} {"level":"warn","ts":"2026-09-07T18:56:08.601206Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T18:56:08.276805Z","time spent":"324.39301ms","remote":"127.0.0.1:51652","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":0,"response size":28,"request content":"key:\"/registry/jobs/openstack/nova-rabbit-init\" "} {"level":"warn","ts":"2026-09-07T18:56:08.601286Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.205514ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-07T18:56:08.601323Z","caller":"traceutil/trace.go:171","msg":"trace[1008550524] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:16320; }","duration":"270.291986ms","start":"2026-09-07T18:56:08.331022Z","end":"2026-09-07T18:56:08.601314Z","steps":["trace[1008550524] 'agreement among raft nodes before linearized reading' (duration: 270.163712ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T18:56:08.601167Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T18:56:08.285696Z","time spent":"315.461479ms","remote":"127.0.0.1:51652","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":5546,"request content":"key:\"/registry/jobs/openstack/nova-db-sync\" "} {"level":"warn","ts":"2026-09-07T18:56:08.601052Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"324.230825ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:668"} {"level":"info","ts":"2026-09-07T18:56:08.601455Z","caller":"traceutil/trace.go:171","msg":"trace[2104724654] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:16320; }","duration":"324.70085ms","start":"2026-09-07T18:56:08.276735Z","end":"2026-09-07T18:56:08.601436Z","steps":["trace[2104724654] 'agreement among raft nodes before linearized reading' (duration: 324.141333ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T18:56:08.601606Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T18:56:08.276714Z","time spent":"324.838724ms","remote":"127.0.0.1:51592","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":691,"request content":"key:\"/registry/services/endpoints/openstack/nova-api\" "} {"level":"info","ts":"2026-09-07T18:57:55.877703Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":14541} {"level":"info","ts":"2026-09-07T18:57:55.958551Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":14541,"took":"77.999365ms","hash":3618124091,"current-db-size-bytes":53743616,"current-db-size":"54 MB","current-db-size-in-use-bytes":31580160,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-09-07T18:57:55.958658Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3618124091,"revision":14541,"compact-revision":12538} {"level":"info","ts":"2026-09-07T18:59:09.352878Z","caller":"traceutil/trace.go:171","msg":"trace[867068130] linearizableReadLoop","detail":"{readStateIndex:18329; appliedIndex:18328; }","duration":"121.288803ms","start":"2026-09-07T18:59:09.231565Z","end":"2026-09-07T18:59:09.352854Z","steps":["trace[867068130] 'read index received' (duration: 121.141318ms)","trace[867068130] 'applied index is now lower than readState.Index' (duration: 146.355µs)"],"step_count":2} {"level":"info","ts":"2026-09-07T18:59:09.352938Z","caller":"traceutil/trace.go:171","msg":"trace[838427610] transaction","detail":"{read_only:false; response_revision:17719; number_of_response:1; }","duration":"148.657264ms","start":"2026-09-07T18:59:09.204239Z","end":"2026-09-07T18:59:09.352896Z","steps":["trace[838427610] 'process raft request' (duration: 148.459448ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T18:59:09.353092Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.507269ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-09-07T18:59:09.353132Z","caller":"traceutil/trace.go:171","msg":"trace[636532028] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:17719; }","duration":"121.615272ms","start":"2026-09-07T18:59:09.231506Z","end":"2026-09-07T18:59:09.353122Z","steps":["trace[636532028] 'agreement among raft nodes before linearized reading' (duration: 121.476128ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T18:59:09.367203Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.351575ms","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-09-07T18:59:09.367301Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.475039ms","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":"warn","ts":"2026-09-07T18:59:09.367331Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.268736ms","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-09-07T18:59:09.367384Z","caller":"traceutil/trace.go:171","msg":"trace[533712381] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:17721; }","duration":"133.609042ms","start":"2026-09-07T18:59:09.233755Z","end":"2026-09-07T18:59:09.367364Z","steps":["trace[533712381] 'agreement among raft nodes before linearized reading' (duration: 133.374046ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T18:59:09.367388Z","caller":"traceutil/trace.go:171","msg":"trace[1661736107] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:17721; }","duration":"128.358379ms","start":"2026-09-07T18:59:09.239016Z","end":"2026-09-07T18:59:09.367375Z","steps":["trace[1661736107] 'agreement among raft nodes before linearized reading' (duration: 128.07376ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T18:59:09.367313Z","caller":"traceutil/trace.go:171","msg":"trace[1787410935] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:17721; }","duration":"134.52083ms","start":"2026-09-07T18:59:09.23277Z","end":"2026-09-07T18:59:09.36729Z","steps":["trace[1787410935] 'agreement among raft nodes before linearized reading' (duration: 134.246002ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T18:59:09.766572Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.662224ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-07T18:59:09.766755Z","caller":"traceutil/trace.go:171","msg":"trace[66170530] transaction","detail":"{read_only:false; response_revision:17728; number_of_response:1; }","duration":"351.599117ms","start":"2026-09-07T18:59:09.415122Z","end":"2026-09-07T18:59:09.766721Z","steps":["trace[66170530] 'process raft request' (duration: 92.703145ms)","trace[66170530] 'compare' (duration: 258.50525ms)"],"step_count":2} {"level":"warn","ts":"2026-09-07T18:59:09.7669Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T18:59:09.415103Z","time spent":"351.71815ms","remote":"127.0.0.1:51672","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-07T19:01:48.669123Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.407869ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-07T19:01:48.669303Z","caller":"traceutil/trace.go:171","msg":"trace[201580332] linearizableReadLoop","detail":"{readStateIndex:19510; appliedIndex:19509; }","duration":"168.543056ms","start":"2026-09-07T19:01:48.500696Z","end":"2026-09-07T19:01:48.669239Z","steps":["trace[201580332] 'read index received' (duration: 84.032µs)","trace[201580332] 'applied index is now lower than readState.Index' (duration: 168.457464ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T19:01:48.669348Z","caller":"traceutil/trace.go:171","msg":"trace[34330799] transaction","detail":"{read_only:false; response_revision:18863; number_of_response:1; }","duration":"237.201068ms","start":"2026-09-07T19:01:48.432094Z","end":"2026-09-07T19:01:48.669295Z","steps":["trace[34330799] 'process raft request' (duration: 49.130439ms)","trace[34330799] 'compare' (duration: 187.232434ms)"],"step_count":2} {"level":"warn","ts":"2026-09-07T19:01:48.66996Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.009175ms","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:6"} {"level":"info","ts":"2026-09-07T19:01:48.67009Z","caller":"traceutil/trace.go:171","msg":"trace[1371427362] range","detail":"{range_begin:/registry/ceph.rook.io/cephcosidrivers/; range_end:/registry/ceph.rook.io/cephcosidrivers0; response_count:0; response_revision:18863; }","duration":"120.230602ms","start":"2026-09-07T19:01:48.549825Z","end":"2026-09-07T19:01:48.670056Z","steps":["trace[1371427362] 'agreement among raft nodes before linearized reading' (duration: 119.957844ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T19:01:48.670458Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.770292ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/nfd.k8s-sigs.io/nodefeatures/monitoring/instance\" ","response":"range_response_count:1 size:418021"} {"level":"info","ts":"2026-09-07T19:01:48.670499Z","caller":"traceutil/trace.go:171","msg":"trace[202929189] range","detail":"{range_begin:/registry/nfd.k8s-sigs.io/nodefeatures/monitoring/instance; range_end:; response_count:1; response_revision:18863; }","duration":"169.861235ms","start":"2026-09-07T19:01:48.500627Z","end":"2026-09-07T19:01:48.670488Z","steps":["trace[202929189] 'agreement among raft nodes before linearized reading' (duration: 168.793424ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T19:01:49.169486Z","caller":"traceutil/trace.go:171","msg":"trace[1601275037] linearizableReadLoop","detail":"{readStateIndex:19513; appliedIndex:19512; }","duration":"342.941414ms","start":"2026-09-07T19:01:48.826521Z","end":"2026-09-07T19:01:49.169462Z","steps":["trace[1601275037] 'read index received' (duration: 313.922384ms)","trace[1601275037] 'applied index is now lower than readState.Index' (duration: 29.0178ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T19:01:49.169576Z","caller":"traceutil/trace.go:171","msg":"trace[332500291] transaction","detail":"{read_only:false; response_revision:18866; number_of_response:1; }","duration":"345.612992ms","start":"2026-09-07T19:01:48.823954Z","end":"2026-09-07T19:01:49.169567Z","steps":["trace[332500291] 'process raft request' (duration: 316.466299ms)","trace[332500291] 'compare' (duration: 28.911076ms)"],"step_count":2} {"level":"warn","ts":"2026-09-07T19:01:49.169662Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"343.13387ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-cell-setup-29813460-78lx2\" ","response":"range_response_count:1 size:7039"} {"level":"info","ts":"2026-09-07T19:01:49.169691Z","caller":"traceutil/trace.go:171","msg":"trace[611869161] range","detail":"{range_begin:/registry/pods/openstack/nova-cell-setup-29813460-78lx2; range_end:; response_count:1; response_revision:18866; }","duration":"343.202412ms","start":"2026-09-07T19:01:48.826482Z","end":"2026-09-07T19:01:49.169685Z","steps":["trace[611869161] 'agreement among raft nodes before linearized reading' (duration: 343.100119ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T19:01:49.169706Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T19:01:48.82647Z","time spent":"343.231962ms","remote":"127.0.0.1:51602","response type":"/etcdserverpb.KV/Range","request count":0,"request size":57,"response count":1,"response size":7062,"request content":"key:\"/registry/pods/openstack/nova-cell-setup-29813460-78lx2\" "} {"level":"warn","ts":"2026-09-07T19:01:49.169909Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T19:01:48.823932Z","time spent":"345.662434ms","remote":"127.0.0.1:35920","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1869,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-07T19:01:49.179605Z","caller":"traceutil/trace.go:171","msg":"trace[860400473] transaction","detail":"{read_only:false; response_revision:18867; number_of_response:1; }","duration":"294.30514ms","start":"2026-09-07T19:01:48.885276Z","end":"2026-09-07T19:01:49.179581Z","steps":["trace[860400473] 'process raft request' (duration: 294.201507ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T19:01:49.180036Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.301324ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-07T19:01:49.180067Z","caller":"traceutil/trace.go:171","msg":"trace[704215836] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:18867; }","duration":"190.393016ms","start":"2026-09-07T19:01:48.989667Z","end":"2026-09-07T19:01:49.18006Z","steps":["trace[704215836] 'agreement among raft nodes before linearized reading' (duration: 190.329044ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T19:01:49.180232Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.518876ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusterbackups/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusterbackups0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-07T19:01:49.180338Z","caller":"traceutil/trace.go:171","msg":"trace[343830472] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusterbackups/; range_end:/registry/pxc.percona.com/perconaxtradbclusterbackups0; response_count:0; response_revision:18867; }","duration":"209.65944ms","start":"2026-09-07T19:01:48.97066Z","end":"2026-09-07T19:01:49.180319Z","steps":["trace[343830472] 'agreement among raft nodes before linearized reading' (duration: 209.450734ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T19:01:49.180464Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.579088ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-09-07T19:01:49.180563Z","caller":"traceutil/trace.go:171","msg":"trace[1832225642] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:18867; }","duration":"251.747764ms","start":"2026-09-07T19:01:48.928791Z","end":"2026-09-07T19:01:49.180539Z","steps":["trace[1832225642] 'agreement among raft nodes before linearized reading' (duration: 251.399243ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T19:01:49.715902Z","caller":"traceutil/trace.go:171","msg":"trace[1858193395] linearizableReadLoop","detail":"{readStateIndex:19518; appliedIndex:19517; }","duration":"461.114744ms","start":"2026-09-07T19:01:49.254757Z","end":"2026-09-07T19:01:49.715872Z","steps":["trace[1858193395] 'read index received' (duration: 460.817046ms)","trace[1858193395] 'applied index is now lower than readState.Index' (duration: 296.748µs)"],"step_count":2} {"level":"info","ts":"2026-09-07T19:01:49.716045Z","caller":"traceutil/trace.go:171","msg":"trace[87707163] transaction","detail":"{read_only:false; response_revision:18871; number_of_response:1; }","duration":"470.179571ms","start":"2026-09-07T19:01:49.245813Z","end":"2026-09-07T19:01:49.715993Z","steps":["trace[87707163] 'process raft request' (duration: 469.846791ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T19:01:49.716169Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"461.399403ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:19623"} {"level":"info","ts":"2026-09-07T19:01:49.716206Z","caller":"traceutil/trace.go:171","msg":"trace[100174799] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:18871; }","duration":"461.480215ms","start":"2026-09-07T19:01:49.254714Z","end":"2026-09-07T19:01:49.716194Z","steps":["trace[100174799] 'agreement among raft nodes before linearized reading' (duration: 461.318331ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T19:01:49.71624Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T19:01:49.254697Z","time spent":"461.535986ms","remote":"127.0.0.1:51600","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":19646,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-09-07T19:01:49.716327Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T19:01:49.245779Z","time spent":"470.376076ms","remote":"127.0.0.1:51602","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":20867,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-07T19:01:49.950037Z","caller":"traceutil/trace.go:171","msg":"trace[793865998] transaction","detail":"{read_only:false; response_revision:18872; number_of_response:1; }","duration":"693.563593ms","start":"2026-09-07T19:01:49.256446Z","end":"2026-09-07T19:01:49.95001Z","steps":["trace[793865998] 'process raft request' (duration: 693.270184ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T19:01:49.950182Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T19:01:49.256427Z","time spent":"693.685306ms","remote":"127.0.0.1:51592","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":738,"response count":0,"response size":41,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-07T19:01:49.952407Z","caller":"traceutil/trace.go:171","msg":"trace[312987389] transaction","detail":"{read_only:false; response_revision:18873; number_of_response:1; }","duration":"695.740896ms","start":"2026-09-07T19:01:49.256613Z","end":"2026-09-07T19:01:49.952354Z","steps":["trace[312987389] 'process raft request' (duration: 693.279804ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T19:01:49.952605Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T19:01:49.256585Z","time spent":"695.929612ms","remote":"127.0.0.1:51510","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":716,"response count":0,"response size":41,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-07T19:01:49.9528Z","caller":"traceutil/trace.go:171","msg":"trace[2003677256] transaction","detail":"{read_only:false; response_revision:18874; number_of_response:1; }","duration":"694.946253ms","start":"2026-09-07T19:01:49.257718Z","end":"2026-09-07T19:01:49.952664Z","steps":["trace[2003677256] 'process raft request' (duration: 694.48645ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T19:01:49.954205Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T19:01:49.2577Z","time spent":"695.150029ms","remote":"127.0.0.1:51682","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1225,"response count":0,"response size":41,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-07T19:01:49.955162Z","caller":"traceutil/trace.go:171","msg":"trace[547746881] linearizableReadLoop","detail":"{readStateIndex:19522; appliedIndex:19518; }","duration":"239.135074ms","start":"2026-09-07T19:01:49.716006Z","end":"2026-09-07T19:01:49.955141Z","steps":["trace[547746881] 'read index received' (duration: 233.711425ms)","trace[547746881] 'applied index is now lower than readState.Index' (duration: 5.422099ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T19:01:49.955916Z","caller":"traceutil/trace.go:171","msg":"trace[2060744288] transaction","detail":"{read_only:false; response_revision:18875; number_of_response:1; }","duration":"670.695934ms","start":"2026-09-07T19:01:49.285204Z","end":"2026-09-07T19:01:49.9559Z","steps":["trace[2060744288] 'process raft request' (duration: 669.619383ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T19:01:49.956022Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T19:01:49.285158Z","time spent":"670.796746ms","remote":"127.0.0.1:44772","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417929,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-07T19:01:49.95625Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.53374ms","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-09-07T19:01:49.956336Z","caller":"traceutil/trace.go:171","msg":"trace[669090466] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:18875; }","duration":"148.674435ms","start":"2026-09-07T19:01:49.807647Z","end":"2026-09-07T19:01:49.956322Z","steps":["trace[669090466] 'agreement among raft nodes before linearized reading' (duration: 148.558291ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T19:01:49.956435Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"699.761104ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:1 size:130325"} {"level":"info","ts":"2026-09-07T19:01:49.956513Z","caller":"traceutil/trace.go:171","msg":"trace[351223765] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:1; response_revision:18875; }","duration":"699.872638ms","start":"2026-09-07T19:01:49.256621Z","end":"2026-09-07T19:01:49.956494Z","steps":["trace[351223765] 'agreement among raft nodes before linearized reading' (duration: 699.524148ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T19:01:49.956553Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T19:01:49.256611Z","time spent":"699.934919ms","remote":"127.0.0.1:51504","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":1,"response size":130348,"request content":"key:\"/registry/ranges/serviceips\" "} {"level":"warn","ts":"2026-09-07T19:01:49.956893Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"554.462729ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.14\" ","response":"range_response_count:1 size:137"} {"level":"info","ts":"2026-09-07T19:01:49.956947Z","caller":"traceutil/trace.go:171","msg":"trace[2079710121] range","detail":"{range_begin:/registry/masterleases/162.253.55.14; range_end:; response_count:1; response_revision:18875; }","duration":"554.574232ms","start":"2026-09-07T19:01:49.402359Z","end":"2026-09-07T19:01:49.956933Z","steps":["trace[2079710121] 'agreement among raft nodes before linearized reading' (duration: 554.447969ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T19:01:49.956986Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T19:01:49.402334Z","time spent":"554.641264ms","remote":"127.0.0.1:51476","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":160,"request content":"key:\"/registry/masterleases/162.253.55.14\" "} {"level":"warn","ts":"2026-09-07T19:01:49.957305Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"651.927352ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-09-07T19:01:49.957351Z","caller":"traceutil/trace.go:171","msg":"trace[675789580] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; response_count:0; response_revision:18875; }","duration":"651.971785ms","start":"2026-09-07T19:01:49.305363Z","end":"2026-09-07T19:01:49.957335Z","steps":["trace[675789580] 'agreement among raft nodes before linearized reading' (duration: 651.80197ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T19:01:49.957381Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T19:01:49.305306Z","time spent":"652.066857ms","remote":"127.0.0.1:51490","response type":"/etcdserverpb.KV/Range","request count":0,"request size":120,"response count":92,"response size":31,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true "} {"level":"info","ts":"2026-09-07T19:01:58.129013Z","caller":"traceutil/trace.go:171","msg":"trace[1796485780] transaction","detail":"{read_only:false; response_revision:19020; number_of_response:1; }","duration":"111.310001ms","start":"2026-09-07T19:01:58.017679Z","end":"2026-09-07T19:01:58.128989Z","steps":["trace[1796485780] 'process raft request' (duration: 111.154017ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T19:01:58.129747Z","caller":"traceutil/trace.go:171","msg":"trace[1619675984] linearizableReadLoop","detail":"{readStateIndex:19672; appliedIndex:19672; }","duration":"103.295585ms","start":"2026-09-07T19:01:58.026441Z","end":"2026-09-07T19:01:58.129737Z","steps":["trace[1619675984] 'read index received' (duration: 103.289275ms)","trace[1619675984] 'applied index is now lower than readState.Index' (duration: 5.25µs)"],"step_count":2} {"level":"warn","ts":"2026-09-07T19:01:58.130126Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.661558ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated\" ","response":"range_response_count:1 size:1344"} {"level":"info","ts":"2026-09-07T19:01:58.130153Z","caller":"traceutil/trace.go:171","msg":"trace[627310650] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated; range_end:; response_count:1; response_revision:19020; }","duration":"103.728029ms","start":"2026-09-07T19:01:58.026413Z","end":"2026-09-07T19:01:58.130141Z","steps":["trace[627310650] 'agreement among raft nodes before linearized reading' (duration: 103.381688ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T19:01:58.140243Z","caller":"traceutil/trace.go:171","msg":"trace[1802935869] transaction","detail":"{read_only:false; response_revision:19021; number_of_response:1; }","duration":"113.077973ms","start":"2026-09-07T19:01:58.027134Z","end":"2026-09-07T19:01:58.140212Z","steps":["trace[1802935869] 'process raft request' (duration: 111.453655ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T19:01:58.14052Z","caller":"traceutil/trace.go:171","msg":"trace[1961221629] transaction","detail":"{read_only:false; response_revision:19022; number_of_response:1; }","duration":"113.152934ms","start":"2026-09-07T19:01:58.027354Z","end":"2026-09-07T19:01:58.140507Z","steps":["trace[1961221629] 'process raft request' (duration: 112.193016ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T19:02:24.048395Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"5db0a1b394e67b72","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-09-07T19:02:24.060942Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-09-07T19:02:24.06105Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-09-07T19:02:55.885824Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17002} {"level":"info","ts":"2026-09-07T19:02:55.998343Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17002,"took":"108.877689ms","hash":2184162787,"current-db-size-bytes":53743616,"current-db-size":"54 MB","current-db-size-in-use-bytes":34713600,"current-db-size-in-use":"35 MB"} {"level":"info","ts":"2026-09-07T19:02:55.999059Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2184162787,"revision":17002,"compact-revision":14541} {"level":"warn","ts":"2026-09-07T19:07:39.840341Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"340.602927ms","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:6"} {"level":"info","ts":"2026-09-07T19:07:39.840432Z","caller":"traceutil/trace.go:171","msg":"trace[348119056] range","detail":"{range_begin:/registry/objectbucket.io/objectbucketclaims/; range_end:/registry/objectbucket.io/objectbucketclaims0; response_count:0; response_revision:21613; }","duration":"340.747663ms","start":"2026-09-07T19:07:39.499664Z","end":"2026-09-07T19:07:39.840412Z","steps":["trace[348119056] 'count revisions from in-memory index tree' (duration: 340.495245ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T19:07:39.840473Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T19:07:39.499638Z","time spent":"340.823754ms","remote":"127.0.0.1:57908","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":29,"request content":"key:\"/registry/objectbucket.io/objectbucketclaims/\" range_end:\"/registry/objectbucket.io/objectbucketclaims0\" count_only:true "} {"level":"warn","ts":"2026-09-07T19:07:39.840766Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.292276ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-07T19:07:39.841064Z","caller":"traceutil/trace.go:171","msg":"trace[655547427] transaction","detail":"{read_only:false; response_revision:21614; number_of_response:1; }","duration":"337.042814ms","start":"2026-09-07T19:07:39.503973Z","end":"2026-09-07T19:07:39.841015Z","steps":["trace[655547427] 'process raft request' (duration: 148.382687ms)","trace[655547427] 'compare' (duration: 187.721589ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T19:07:39.841106Z","caller":"traceutil/trace.go:171","msg":"trace[637206109] transaction","detail":"{read_only:false; response_revision:21615; number_of_response:1; }","duration":"292.758796ms","start":"2026-09-07T19:07:39.548322Z","end":"2026-09-07T19:07:39.841081Z","steps":["trace[637206109] 'process raft request' (duration: 292.578401ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T19:07:39.841162Z","caller":"traceutil/trace.go:171","msg":"trace[561706636] transaction","detail":"{read_only:false; response_revision:21616; number_of_response:1; }","duration":"286.145732ms","start":"2026-09-07T19:07:39.555001Z","end":"2026-09-07T19:07:39.841147Z","steps":["trace[561706636] 'process raft request' (duration: 286.026969ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T19:07:39.84125Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T19:07:39.503954Z","time spent":"337.197908ms","remote":"127.0.0.1:51672","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-07T19:07:40.239804Z","caller":"traceutil/trace.go:171","msg":"trace[570225700] linearizableReadLoop","detail":"{readStateIndex:22354; appliedIndex:22353; }","duration":"249.85697ms","start":"2026-09-07T19:07:39.989908Z","end":"2026-09-07T19:07:40.239765Z","steps":["trace[570225700] 'read index received' (duration: 249.565111ms)","trace[570225700] 'applied index is now lower than readState.Index' (duration: 289.729µs)"],"step_count":2} {"level":"info","ts":"2026-09-07T19:07:40.239863Z","caller":"traceutil/trace.go:171","msg":"trace[1095684676] transaction","detail":"{read_only:false; response_revision:21617; number_of_response:1; }","duration":"366.234159ms","start":"2026-09-07T19:07:39.873592Z","end":"2026-09-07T19:07:40.239826Z","steps":["trace[1095684676] 'process raft request' (duration: 365.880059ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T19:07:40.240022Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T19:07:39.873552Z","time spent":"366.384094ms","remote":"127.0.0.1:51672","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":544,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-07T19:07:40.240076Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.136308ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-07T19:07:40.240126Z","caller":"traceutil/trace.go:171","msg":"trace[305836895] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:21617; }","duration":"250.262482ms","start":"2026-09-07T19:07:39.989849Z","end":"2026-09-07T19:07:40.240111Z","steps":["trace[305836895] 'agreement among raft nodes before linearized reading' (duration: 250.142138ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T19:07:40.240207Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.836972ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/federations/\" range_end:\"/registry/rabbitmq.com/federations0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-07T19:07:40.240299Z","caller":"traceutil/trace.go:171","msg":"trace[1426065042] range","detail":"{range_begin:/registry/rabbitmq.com/federations/; range_end:/registry/rabbitmq.com/federations0; response_count:0; response_revision:21617; }","duration":"117.952005ms","start":"2026-09-07T19:07:40.122324Z","end":"2026-09-07T19:07:40.240276Z","steps":["trace[1426065042] 'agreement among raft nodes before linearized reading' (duration: 117.78088ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T19:07:40.549197Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.506978ms","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-09-07T19:07:40.549312Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.649397ms","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":"warn","ts":"2026-09-07T19:07:40.549386Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.9133ms","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-09-07T19:07:40.549362Z","caller":"traceutil/trace.go:171","msg":"trace[1791210494] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:21617; }","duration":"181.718824ms","start":"2026-09-07T19:07:40.367621Z","end":"2026-09-07T19:07:40.54934Z","steps":["trace[1791210494] 'range keys from in-memory index tree' (duration: 181.334773ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T19:07:40.549216Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.114422ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-09-07T19:07:40.54964Z","caller":"traceutil/trace.go:171","msg":"trace[542041310] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:21617; }","duration":"229.576036ms","start":"2026-09-07T19:07:40.320043Z","end":"2026-09-07T19:07:40.549619Z","steps":["trace[542041310] 'range keys from in-memory index tree' (duration: 228.947537ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T19:07:40.54942Z","caller":"traceutil/trace.go:171","msg":"trace[1167079711] range","detail":"{range_begin:/registry/certificatesigningrequests/; range_end:/registry/certificatesigningrequests0; response_count:0; response_revision:21617; }","duration":"145.76706ms","start":"2026-09-07T19:07:40.403623Z","end":"2026-09-07T19:07:40.54939Z","steps":["trace[1167079711] 'count revisions from in-memory index tree' (duration: 145.518334ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T19:07:40.54954Z","caller":"traceutil/trace.go:171","msg":"trace[859414260] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:21617; }","duration":"238.109876ms","start":"2026-09-07T19:07:40.311371Z","end":"2026-09-07T19:07:40.549481Z","steps":["trace[859414260] 'range keys from in-memory index tree' (duration: 237.600541ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T19:07:40.706539Z","caller":"traceutil/trace.go:171","msg":"trace[1109212055] transaction","detail":"{read_only:false; response_revision:21618; number_of_response:1; }","duration":"152.417036ms","start":"2026-09-07T19:07:40.55409Z","end":"2026-09-07T19:07:40.706507Z","steps":["trace[1109212055] 'process raft request' (duration: 152.091076ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T19:07:40.908668Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.844649ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-07T19:07:40.908847Z","caller":"traceutil/trace.go:171","msg":"trace[2132270102] linearizableReadLoop","detail":"{readStateIndex:22357; appliedIndex:22354; }","duration":"310.812435ms","start":"2026-09-07T19:07:40.598016Z","end":"2026-09-07T19:07:40.908829Z","steps":["trace[2132270102] 'read index received' (duration: 108.316893ms)","trace[2132270102] 'applied index is now lower than readState.Index' (duration: 202.494362ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T19:07:40.908895Z","caller":"traceutil/trace.go:171","msg":"trace[1181245347] transaction","detail":"{read_only:false; response_revision:21620; number_of_response:1; }","duration":"353.859027ms","start":"2026-09-07T19:07:40.555003Z","end":"2026-09-07T19:07:40.908862Z","steps":["trace[1181245347] 'process raft request' (duration: 353.768015ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T19:07:40.909103Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"311.087673ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-09-07T19:07:40.909163Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T19:07:40.554984Z","time spent":"353.98099ms","remote":"127.0.0.1:51672","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-07T19:07:40.909185Z","caller":"traceutil/trace.go:171","msg":"trace[101666073] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:21620; }","duration":"311.223527ms","start":"2026-09-07T19:07:40.597945Z","end":"2026-09-07T19:07:40.909169Z","steps":["trace[101666073] 'agreement among raft nodes before linearized reading' (duration: 310.98824ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T19:07:40.909164Z","caller":"traceutil/trace.go:171","msg":"trace[2014181081] transaction","detail":"{read_only:false; response_revision:21619; number_of_response:1; }","duration":"354.449414ms","start":"2026-09-07T19:07:40.554567Z","end":"2026-09-07T19:07:40.909017Z","steps":["trace[2014181081] 'process raft request' (duration: 203.160032ms)","trace[2014181081] 'compare' (duration: 150.671243ms)"],"step_count":2} {"level":"warn","ts":"2026-09-07T19:07:40.909243Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T19:07:40.597924Z","time spent":"311.30637ms","remote":"127.0.0.1:51672","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-09-07T19:07:40.909544Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T19:07:40.554529Z","time spent":"354.708001ms","remote":"127.0.0.1:51672","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-07T19:07:41.207812Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.799226ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-07T19:07:41.207989Z","caller":"traceutil/trace.go:171","msg":"trace[1735903977] transaction","detail":"{read_only:false; response_revision:21622; number_of_response:1; }","duration":"293.285291ms","start":"2026-09-07T19:07:40.914686Z","end":"2026-09-07T19:07:41.207971Z","steps":["trace[1735903977] 'process raft request' (duration: 293.223419ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T19:07:41.208099Z","caller":"traceutil/trace.go:171","msg":"trace[1131795284] transaction","detail":"{read_only:false; response_revision:21621; number_of_response:1; }","duration":"294.512537ms","start":"2026-09-07T19:07:40.913527Z","end":"2026-09-07T19:07:41.20804Z","steps":["trace[1131795284] 'process raft request' (duration: 128.384301ms)","trace[1131795284] 'compare' (duration: 165.694643ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T19:07:41.627745Z","caller":"traceutil/trace.go:171","msg":"trace[1862411354] linearizableReadLoop","detail":"{readStateIndex:22360; appliedIndex:22359; }","duration":"310.540567ms","start":"2026-09-07T19:07:41.317162Z","end":"2026-09-07T19:07:41.627702Z","steps":["trace[1862411354] 'read index received' (duration: 310.091794ms)","trace[1862411354] 'applied index is now lower than readState.Index' (duration: 446.703µs)"],"step_count":2} {"level":"info","ts":"2026-09-07T19:07:41.627794Z","caller":"traceutil/trace.go:171","msg":"trace[1932659215] transaction","detail":"{read_only:false; response_revision:21623; number_of_response:1; }","duration":"328.282716ms","start":"2026-09-07T19:07:41.299458Z","end":"2026-09-07T19:07:41.627741Z","steps":["trace[1932659215] 'process raft request' (duration: 327.871495ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T19:07:41.628018Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T19:07:41.299424Z","time spent":"328.479082ms","remote":"127.0.0.1:51672","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-07T19:07:41.628163Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"310.95718ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-09-07T19:07:41.628278Z","caller":"traceutil/trace.go:171","msg":"trace[934459283] range","detail":"{range_begin:/registry/rolebindings/; range_end:/registry/rolebindings0; response_count:0; response_revision:21623; }","duration":"311.120744ms","start":"2026-09-07T19:07:41.317129Z","end":"2026-09-07T19:07:41.62825Z","steps":["trace[934459283] 'agreement among raft nodes before linearized reading' (duration: 310.725323ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T19:07:41.628349Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T19:07:41.317111Z","time spent":"311.221428ms","remote":"127.0.0.1:51746","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":158,"response size":32,"request content":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true "} {"level":"info","ts":"2026-09-07T19:07:55.894526Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":19659} {"level":"info","ts":"2026-09-07T19:07:56.059288Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":19659,"took":"161.12208ms","hash":1988310059,"current-db-size-bytes":53743616,"current-db-size":"54 MB","current-db-size-in-use-bytes":29945856,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-09-07T19:07:56.059622Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1988310059,"revision":19659,"compact-revision":17002} {"level":"info","ts":"2026-09-07T19:11:41.936285Z","caller":"traceutil/trace.go:171","msg":"trace[1197536717] linearizableReadLoop","detail":"{readStateIndex:24927; appliedIndex:24926; }","duration":"101.896685ms","start":"2026-09-07T19:11:41.834359Z","end":"2026-09-07T19:11:41.936256Z","steps":["trace[1197536717] 'read index received' (duration: 101.522705ms)","trace[1197536717] 'applied index is now lower than readState.Index' (duration: 372.61µs)"],"step_count":2} {"level":"info","ts":"2026-09-07T19:11:41.936447Z","caller":"traceutil/trace.go:171","msg":"trace[1060746210] transaction","detail":"{read_only:false; response_revision:24111; number_of_response:1; }","duration":"134.101968ms","start":"2026-09-07T19:11:41.802331Z","end":"2026-09-07T19:11:41.936433Z","steps":["trace[1060746210] 'process raft request' (duration: 133.686326ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T19:11:41.936628Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.251676ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-09-07T19:11:41.936746Z","caller":"traceutil/trace.go:171","msg":"trace[1452453050] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/; range_end:/registry/apiregistration.k8s.io/apiservices0; response_count:0; response_revision:24111; }","duration":"102.417181ms","start":"2026-09-07T19:11:41.834304Z","end":"2026-09-07T19:11:41.936722Z","steps":["trace[1452453050] 'agreement among raft nodes before linearized reading' (duration: 102.199914ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T19:11:42.291359Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"303.399869ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-07T19:11:42.291454Z","caller":"traceutil/trace.go:171","msg":"trace[2124004671] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24111; }","duration":"303.543882ms","start":"2026-09-07T19:11:41.987884Z","end":"2026-09-07T19:11:42.291428Z","steps":["trace[2124004671] 'range keys from in-memory index tree' (duration: 303.259973ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T19:11:42.291509Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T19:11:41.987859Z","time spent":"303.638425ms","remote":"127.0.0.1:51444","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-09-07T19:11:42.292171Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.129579ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-09-07T19:11:42.292254Z","caller":"traceutil/trace.go:171","msg":"trace[284634271] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24111; }","duration":"262.293813ms","start":"2026-09-07T19:11:42.029942Z","end":"2026-09-07T19:11:42.292236Z","steps":["trace[284634271] 'range keys from in-memory index tree' (duration: 261.899503ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T19:11:42.292372Z","caller":"traceutil/trace.go:171","msg":"trace[806571914] transaction","detail":"{read_only:false; response_revision:24112; number_of_response:1; }","duration":"221.21346ms","start":"2026-09-07T19:11:42.071116Z","end":"2026-09-07T19:11:42.292329Z","steps":["trace[806571914] 'process raft request' (duration: 215.079811ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T19:11:42.29242Z","caller":"traceutil/trace.go:171","msg":"trace[579628116] transaction","detail":"{read_only:false; response_revision:24113; number_of_response:1; }","duration":"136.011084ms","start":"2026-09-07T19:11:42.156284Z","end":"2026-09-07T19:11:42.292295Z","steps":["trace[579628116] 'process raft request' (duration: 135.897061ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T19:12:55.90475Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":21697} {"level":"info","ts":"2026-09-07T19:12:56.026233Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":21697,"took":"118.484781ms","hash":1041925537,"current-db-size-bytes":57020416,"current-db-size":"57 MB","current-db-size-in-use-bytes":43487232,"current-db-size-in-use":"44 MB"} {"level":"info","ts":"2026-09-07T19:12:56.026304Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1041925537,"revision":21697,"compact-revision":19659} {"level":"info","ts":"2026-09-07T19:17:55.91176Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":24889} {"level":"info","ts":"2026-09-07T19:17:56.072062Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":24889,"took":"156.663009ms","hash":2663623251,"current-db-size-bytes":57020416,"current-db-size":"57 MB","current-db-size-in-use-bytes":31219712,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-09-07T19:17:56.072794Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2663623251,"revision":24889,"compact-revision":21697} {"level":"info","ts":"2026-09-07T19:21:09.748657Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-0000000000007396.wal"} {"level":"info","ts":"2026-09-07T19:22:04.780598Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"5db0a1b394e67b72","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-09-07T19:22:04.786816Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-09-07T19:22:04.786957Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003}