{"level":"warn","ts":"2026-09-09T01:07:51.729306Z","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-09T01:07:51.729419Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.242: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.242:2380","--initial-cluster=instance=https://162.253.55.242:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.242:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.242: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-09T01:07:51.729485Z","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-09T01:07:51.729496Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.242:2380"]} {"level":"info","ts":"2026-09-09T01:07:51.729516Z","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-09T01:07:51.730155Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.242:2379"]} {"level":"info","ts":"2026-09-09T01:07:51.730233Z","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.242:2380"],"listen-peer-urls":["https://162.253.55.242:2380"],"advertise-client-urls":["https://162.253.55.242:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.242:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.242: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-09T01:07:51.73302Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"2.538255ms"} {"level":"info","ts":"2026-09-09T01:07:51.739112Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"cc70bec9404419f6","cluster-id":"38671954b404c50e"} {"level":"info","ts":"2026-09-09T01:07:51.739193Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cc70bec9404419f6 switched to configuration voters=()"} {"level":"info","ts":"2026-09-09T01:07:51.739217Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cc70bec9404419f6 became follower at term 0"} {"level":"info","ts":"2026-09-09T01:07:51.739232Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft cc70bec9404419f6 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-09-09T01:07:51.739242Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cc70bec9404419f6 became follower at term 1"} {"level":"info","ts":"2026-09-09T01:07:51.739296Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cc70bec9404419f6 switched to configuration voters=(14731484152704801270)"} {"level":"warn","ts":"2026-09-09T01:07:51.744642Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-09-09T01:07:51.746328Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-09-09T01:07:51.747735Z","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-09T01:07:51.751611Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"cc70bec9404419f6","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-09-09T01:07:51.752041Z","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-09T01:07:51.752288Z","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-09T01:07:51.75231Z","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-09T01:07:51.752416Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"cc70bec9404419f6","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-09-09T01:07:51.753227Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cc70bec9404419f6 switched to configuration voters=(14731484152704801270)"} {"level":"info","ts":"2026-09-09T01:07:51.753807Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"38671954b404c50e","local-member-id":"cc70bec9404419f6","added-peer-id":"cc70bec9404419f6","added-peer-peer-urls":["https://162.253.55.242:2380"]} {"level":"info","ts":"2026-09-09T01:07:51.76223Z","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-09T01:07:51.762424Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.242:2380"} {"level":"info","ts":"2026-09-09T01:07:51.762459Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.242:2380"} {"level":"info","ts":"2026-09-09T01:07:51.762765Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"cc70bec9404419f6","initial-advertise-peer-urls":["https://162.253.55.242:2380"],"listen-peer-urls":["https://162.253.55.242:2380"],"advertise-client-urls":["https://162.253.55.242:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.242:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-09-09T01:07:51.762879Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-09-09T01:07:52.04028Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cc70bec9404419f6 is starting a new election at term 1"} {"level":"info","ts":"2026-09-09T01:07:52.040349Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cc70bec9404419f6 became pre-candidate at term 1"} {"level":"info","ts":"2026-09-09T01:07:52.040388Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cc70bec9404419f6 received MsgPreVoteResp from cc70bec9404419f6 at term 1"} {"level":"info","ts":"2026-09-09T01:07:52.04041Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cc70bec9404419f6 became candidate at term 2"} {"level":"info","ts":"2026-09-09T01:07:52.040421Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cc70bec9404419f6 received MsgVoteResp from cc70bec9404419f6 at term 2"} {"level":"info","ts":"2026-09-09T01:07:52.040436Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cc70bec9404419f6 became leader at term 2"} {"level":"info","ts":"2026-09-09T01:07:52.04045Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: cc70bec9404419f6 elected leader cc70bec9404419f6 at term 2"} {"level":"info","ts":"2026-09-09T01:07:52.041413Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-09-09T01:07:52.042357Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-09T01:07:52.042352Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"cc70bec9404419f6","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.242:2379]}","request-path":"/0/members/cc70bec9404419f6/attributes","cluster-id":"38671954b404c50e","publish-timeout":"7s"} {"level":"info","ts":"2026-09-09T01:07:52.042414Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-09T01:07:52.04272Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-09-09T01:07:52.042782Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-09-09T01:07:52.042994Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"38671954b404c50e","local-member-id":"cc70bec9404419f6","cluster-version":"3.5"} {"level":"info","ts":"2026-09-09T01:07:52.043091Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-09-09T01:07:52.043153Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-09-09T01:07:52.047305Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-09-09T01:07:52.061549Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.242:2379"} {"level":"info","ts":"2026-09-09T01:08:38.308718Z","caller":"traceutil/trace.go:171","msg":"trace[1017227158] transaction","detail":"{read_only:false; response_revision:633; number_of_response:1; }","duration":"112.920569ms","start":"2026-09-09T01:08:38.195759Z","end":"2026-09-09T01:08:38.30868Z","steps":["trace[1017227158] 'process raft request' (duration: 112.610729ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:08:38.395811Z","caller":"traceutil/trace.go:171","msg":"trace[1760564638] transaction","detail":"{read_only:false; response_revision:634; number_of_response:1; }","duration":"116.22233ms","start":"2026-09-09T01:08:38.279554Z","end":"2026-09-09T01:08:38.395777Z","steps":["trace[1760564638] 'process raft request' (duration: 70.419478ms)","trace[1760564638] 'compare' (duration: 44.379769ms)"],"step_count":2} {"level":"warn","ts":"2026-09-09T01:08:38.819554Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.410911ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-09T01:08:38.819792Z","caller":"traceutil/trace.go:171","msg":"trace[968484812] linearizableReadLoop","detail":"{readStateIndex:665; appliedIndex:663; }","duration":"276.23937ms","start":"2026-09-09T01:08:38.543534Z","end":"2026-09-09T01:08:38.819773Z","steps":["trace[968484812] 'read index received' (duration: 61.906101ms)","trace[968484812] 'applied index is now lower than readState.Index' (duration: 214.332429ms)"],"step_count":2} {"level":"warn","ts":"2026-09-09T01:08:38.820169Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.60613ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-wrffm\" ","response":"range_response_count:1 size:16965"} {"level":"warn","ts":"2026-09-09T01:08:38.820184Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.238884ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-09-09T01:08:38.820212Z","caller":"traceutil/trace.go:171","msg":"trace[162115329] range","detail":"{range_begin:/registry/pods/kube-system/cilium-wrffm; range_end:; response_count:1; response_revision:637; }","duration":"276.686763ms","start":"2026-09-09T01:08:38.543514Z","end":"2026-09-09T01:08:38.820201Z","steps":["trace[162115329] 'agreement among raft nodes before linearized reading' (duration: 276.526168ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:08:38.820177Z","caller":"traceutil/trace.go:171","msg":"trace[1793012272] transaction","detail":"{read_only:false; response_revision:637; number_of_response:1; }","duration":"343.47608ms","start":"2026-09-09T01:08:38.476605Z","end":"2026-09-09T01:08:38.820081Z","steps":["trace[1793012272] 'process raft request' (duration: 343.105629ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:08:38.820252Z","caller":"traceutil/trace.go:171","msg":"trace[2104427038] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:637; }","duration":"180.342167ms","start":"2026-09-09T01:08:38.639889Z","end":"2026-09-09T01:08:38.820232Z","steps":["trace[2104427038] 'agreement among raft nodes before linearized reading' (duration: 180.085729ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:08:38.820457Z","caller":"traceutil/trace.go:171","msg":"trace[869719314] transaction","detail":"{read_only:false; response_revision:636; number_of_response:1; }","duration":"380.441273ms","start":"2026-09-09T01:08:38.439343Z","end":"2026-09-09T01:08:38.819784Z","steps":["trace[869719314] 'process raft request' (duration: 166.119845ms)","trace[869719314] 'compare' (duration: 213.256036ms)"],"step_count":2} {"level":"warn","ts":"2026-09-09T01:08:38.820466Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T01:08:38.476559Z","time spent":"343.736458ms","remote":"127.0.0.1:51736","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1092,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-09-09T01:08:38.820595Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T01:08:38.439305Z","time spent":"381.212857ms","remote":"127.0.0.1:51640","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-09T01:08:39.246646Z","caller":"traceutil/trace.go:171","msg":"trace[505436373] linearizableReadLoop","detail":"{readStateIndex:667; appliedIndex:666; }","duration":"115.24023ms","start":"2026-09-09T01:08:39.131363Z","end":"2026-09-09T01:08:39.246603Z","steps":["trace[505436373] 'read index received' (duration: 114.24565ms)","trace[505436373] 'applied index is now lower than readState.Index' (duration: 993.11µs)"],"step_count":2} {"level":"warn","ts":"2026-09-09T01:08:39.246842Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.460507ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-09T01:08:39.246871Z","caller":"traceutil/trace.go:171","msg":"trace[605596722] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:639; }","duration":"115.55776ms","start":"2026-09-09T01:08:39.131302Z","end":"2026-09-09T01:08:39.24686Z","steps":["trace[605596722] 'agreement among raft nodes before linearized reading' (duration: 115.468677ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:08:39.246876Z","caller":"traceutil/trace.go:171","msg":"trace[838202510] transaction","detail":"{read_only:false; response_revision:639; number_of_response:1; }","duration":"406.815994ms","start":"2026-09-09T01:08:38.840031Z","end":"2026-09-09T01:08:39.246847Z","steps":["trace[838202510] 'process raft request' (duration: 405.610088ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:08:39.247031Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T01:08:38.84001Z","time spent":"406.944318ms","remote":"127.0.0.1:51576","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-09T01:08:39.461446Z","caller":"traceutil/trace.go:171","msg":"trace[570989269] transaction","detail":"{read_only:false; response_revision:640; number_of_response:1; }","duration":"152.351417ms","start":"2026-09-09T01:08:39.309052Z","end":"2026-09-09T01:08:39.461404Z","steps":["trace[570989269] 'process raft request' (duration: 151.517811ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:09:23.83498Z","caller":"traceutil/trace.go:171","msg":"trace[968727520] transaction","detail":"{read_only:false; response_revision:1203; number_of_response:1; }","duration":"120.222131ms","start":"2026-09-09T01:09:23.714724Z","end":"2026-09-09T01:09:23.834946Z","steps":["trace[968727520] 'process raft request' (duration: 119.900491ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:09:46.176699Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.130919ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-09T01:09:46.176812Z","caller":"traceutil/trace.go:171","msg":"trace[1551988601] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; response_count:0; response_revision:1464; }","duration":"111.332575ms","start":"2026-09-09T01:09:46.065456Z","end":"2026-09-09T01:09:46.176789Z","steps":["trace[1551988601] 'count revisions from in-memory index tree' (duration: 110.963075ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:10:11.799095Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.188896ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-09T01:10:11.799588Z","caller":"traceutil/trace.go:171","msg":"trace[83559149] transaction","detail":"{read_only:false; response_revision:1869; number_of_response:1; }","duration":"368.244527ms","start":"2026-09-09T01:10:11.431322Z","end":"2026-09-09T01:10:11.799566Z","steps":["trace[83559149] 'process raft request' (duration: 66.470242ms)","trace[83559149] 'compare' (duration: 301.048772ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T01:10:11.799811Z","caller":"traceutil/trace.go:171","msg":"trace[2126542122] transaction","detail":"{read_only:false; response_revision:1870; number_of_response:1; }","duration":"321.769215ms","start":"2026-09-09T01:10:11.478002Z","end":"2026-09-09T01:10:11.799772Z","steps":["trace[2126542122] 'process raft request' (duration: 321.443256ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:10:11.799869Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T01:10:11.43129Z","time spent":"368.524156ms","remote":"127.0.0.1:51640","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-09T01:10:11.799941Z","caller":"traceutil/trace.go:171","msg":"trace[665474694] transaction","detail":"{read_only:false; response_revision:1871; number_of_response:1; }","duration":"229.060618ms","start":"2026-09-09T01:10:11.57083Z","end":"2026-09-09T01:10:11.799891Z","steps":["trace[665474694] 'process raft request' (duration: 228.902363ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:10:11.80005Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T01:10:11.477981Z","time spent":"321.92955ms","remote":"127.0.0.1:51640","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-09T01:10:25.273623Z","caller":"traceutil/trace.go:171","msg":"trace[844871006] linearizableReadLoop","detail":"{readStateIndex:2062; appliedIndex:2061; }","duration":"139.729057ms","start":"2026-09-09T01:10:25.133865Z","end":"2026-09-09T01:10:25.273594Z","steps":["trace[844871006] 'read index received' (duration: 139.4956ms)","trace[844871006] 'applied index is now lower than readState.Index' (duration: 232.307µs)"],"step_count":2} {"level":"info","ts":"2026-09-09T01:10:25.273724Z","caller":"traceutil/trace.go:171","msg":"trace[1859747786] transaction","detail":"{read_only:false; response_revision:1983; number_of_response:1; }","duration":"218.949289ms","start":"2026-09-09T01:10:25.054731Z","end":"2026-09-09T01:10:25.27368Z","steps":["trace[1859747786] 'process raft request' (duration: 218.669511ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:10:25.273917Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.024766ms","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-09T01:10:25.273966Z","caller":"traceutil/trace.go:171","msg":"trace[940901413] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:1983; }","duration":"140.146579ms","start":"2026-09-09T01:10:25.133803Z","end":"2026-09-09T01:10:25.27395Z","steps":["trace[940901413] 'agreement among raft nodes before linearized reading' (duration: 139.938353ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:10:57.485207Z","caller":"traceutil/trace.go:171","msg":"trace[20236510] transaction","detail":"{read_only:false; response_revision:2171; number_of_response:1; }","duration":"314.110999ms","start":"2026-09-09T01:10:57.171069Z","end":"2026-09-09T01:10:57.48518Z","steps":["trace[20236510] 'process raft request' (duration: 313.944545ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:10:57.485436Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T01:10:57.171048Z","time spent":"314.241804ms","remote":"127.0.0.1:51640","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-09T01:10:57.500978Z","caller":"traceutil/trace.go:171","msg":"trace[1529228696] linearizableReadLoop","detail":"{readStateIndex:2258; appliedIndex:2256; }","duration":"281.296067ms","start":"2026-09-09T01:10:57.219648Z","end":"2026-09-09T01:10:57.500944Z","steps":["trace[1529228696] 'read index received' (duration: 265.34376ms)","trace[1529228696] 'applied index is now lower than readState.Index' (duration: 15.951017ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T01:10:57.501085Z","caller":"traceutil/trace.go:171","msg":"trace[2042321300] transaction","detail":"{read_only:false; response_revision:2174; number_of_response:1; }","duration":"105.320978ms","start":"2026-09-09T01:10:57.395746Z","end":"2026-09-09T01:10:57.501067Z","steps":["trace[2042321300] 'process raft request' (duration: 105.148973ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:10:57.501079Z","caller":"traceutil/trace.go:171","msg":"trace[1035875107] transaction","detail":"{read_only:false; response_revision:2173; number_of_response:1; }","duration":"105.470502ms","start":"2026-09-09T01:10:57.395564Z","end":"2026-09-09T01:10:57.501035Z","steps":["trace[1035875107] 'process raft request' (duration: 105.285147ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:10:57.501192Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.499914ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-09T01:10:57.50118Z","caller":"traceutil/trace.go:171","msg":"trace[1427951544] transaction","detail":"{read_only:false; response_revision:2172; number_of_response:1; }","duration":"326.287337ms","start":"2026-09-09T01:10:57.174841Z","end":"2026-09-09T01:10:57.501128Z","steps":["trace[1427951544] 'process raft request' (duration: 325.840644ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:10:57.501235Z","caller":"traceutil/trace.go:171","msg":"trace[486752069] range","detail":"{range_begin:/registry/persistentvolumes/; range_end:/registry/persistentvolumes0; response_count:0; response_revision:2174; }","duration":"281.605516ms","start":"2026-09-09T01:10:57.219618Z","end":"2026-09-09T01:10:57.501223Z","steps":["trace[486752069] 'agreement among raft nodes before linearized reading' (duration: 281.486483ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:10:57.501438Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T01:10:57.174815Z","time spent":"326.475362ms","remote":"127.0.0.1:51640","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-09T01:12:26.161272Z","caller":"traceutil/trace.go:171","msg":"trace[1721373731] transaction","detail":"{read_only:false; response_revision:2846; number_of_response:1; }","duration":"164.429507ms","start":"2026-09-09T01:12:25.99681Z","end":"2026-09-09T01:12:26.161239Z","steps":["trace[1721373731] 'process raft request' (duration: 164.238101ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:12:26.191163Z","caller":"traceutil/trace.go:171","msg":"trace[1633597052] linearizableReadLoop","detail":"{readStateIndex:2961; appliedIndex:2959; }","duration":"104.742201ms","start":"2026-09-09T01:12:26.086379Z","end":"2026-09-09T01:12:26.191122Z","steps":["trace[1633597052] 'read index received' (duration: 74.826781ms)","trace[1633597052] 'applied index is now lower than readState.Index' (duration: 29.91347ms)"],"step_count":2} {"level":"warn","ts":"2026-09-09T01:12:26.191615Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.235126ms","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-09T01:12:26.191725Z","caller":"traceutil/trace.go:171","msg":"trace[98419109] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2847; }","duration":"105.414211ms","start":"2026-09-09T01:12:26.086283Z","end":"2026-09-09T01:12:26.191697Z","steps":["trace[98419109] 'agreement among raft nodes before linearized reading' (duration: 105.103992ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:12:26.192095Z","caller":"traceutil/trace.go:171","msg":"trace[1190236527] transaction","detail":"{read_only:false; response_revision:2847; number_of_response:1; }","duration":"195.208302ms","start":"2026-09-09T01:12:25.996875Z","end":"2026-09-09T01:12:26.192084Z","steps":["trace[1190236527] 'process raft request' (duration: 194.072458ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:12:26.422695Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.12415ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-09T01:12:26.422957Z","caller":"traceutil/trace.go:171","msg":"trace[1126766295] transaction","detail":"{read_only:false; response_revision:2849; number_of_response:1; }","duration":"200.961971ms","start":"2026-09-09T01:12:26.221898Z","end":"2026-09-09T01:12:26.42286Z","steps":["trace[1126766295] 'compare' (duration: 194.958745ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:12:26.617879Z","caller":"traceutil/trace.go:171","msg":"trace[1400642635] linearizableReadLoop","detail":"{readStateIndex:2964; appliedIndex:2963; }","duration":"189.444183ms","start":"2026-09-09T01:12:26.428397Z","end":"2026-09-09T01:12:26.617842Z","steps":["trace[1400642635] 'read index received' (duration: 131.934481ms)","trace[1400642635] 'applied index is now lower than readState.Index' (duration: 57.507662ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T01:12:26.617987Z","caller":"traceutil/trace.go:171","msg":"trace[1835684523] transaction","detail":"{read_only:false; response_revision:2850; number_of_response:1; }","duration":"285.392735ms","start":"2026-09-09T01:12:26.332557Z","end":"2026-09-09T01:12:26.61795Z","steps":["trace[1835684523] 'process raft request' (duration: 227.76258ms)","trace[1835684523] 'compare' (duration: 57.323506ms)"],"step_count":2} {"level":"warn","ts":"2026-09-09T01:12:26.618112Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.68894ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-09T01:12:26.618165Z","caller":"traceutil/trace.go:171","msg":"trace[1767969955] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:2850; }","duration":"189.848726ms","start":"2026-09-09T01:12:26.428303Z","end":"2026-09-09T01:12:26.618152Z","steps":["trace[1767969955] 'agreement among raft nodes before linearized reading' (duration: 189.708241ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:15:40.283213Z","caller":"traceutil/trace.go:171","msg":"trace[1448071358] transaction","detail":"{read_only:false; response_revision:4071; number_of_response:1; }","duration":"120.972112ms","start":"2026-09-09T01:15:40.162213Z","end":"2026-09-09T01:15:40.283185Z","steps":["trace[1448071358] 'process raft request' (duration: 120.762376ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:17:08.697493Z","caller":"traceutil/trace.go:171","msg":"trace[2147076900] transaction","detail":"{read_only:false; response_revision:5065; number_of_response:1; }","duration":"109.792333ms","start":"2026-09-09T01:17:08.587676Z","end":"2026-09-09T01:17:08.697468Z","steps":["trace[2147076900] 'process raft request' (duration: 109.408612ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:17:11.689103Z","caller":"traceutil/trace.go:171","msg":"trace[1343504834] transaction","detail":"{read_only:false; response_revision:5109; number_of_response:1; }","duration":"101.371866ms","start":"2026-09-09T01:17:11.587666Z","end":"2026-09-09T01:17:11.689038Z","steps":["trace[1343504834] 'process raft request' (duration: 99.512162ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:17:15.422176Z","caller":"traceutil/trace.go:171","msg":"trace[86054412] transaction","detail":"{read_only:false; response_revision:5277; number_of_response:1; }","duration":"183.199498ms","start":"2026-09-09T01:17:15.238941Z","end":"2026-09-09T01:17:15.42214Z","steps":["trace[86054412] 'process raft request' (duration: 182.593371ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:17:15.422197Z","caller":"traceutil/trace.go:171","msg":"trace[1788977776] linearizableReadLoop","detail":"{readStateIndex:5480; appliedIndex:5479; }","duration":"177.550863ms","start":"2026-09-09T01:17:15.244533Z","end":"2026-09-09T01:17:15.422084Z","steps":["trace[1788977776] 'read index received' (duration: 176.906654ms)","trace[1788977776] 'applied index is now lower than readState.Index' (duration: 642.129µs)"],"step_count":2} {"level":"warn","ts":"2026-09-09T01:17:15.422543Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.983175ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-c7t57\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-09-09T01:17:15.422649Z","caller":"traceutil/trace.go:171","msg":"trace[1868130388] range","detail":"{range_begin:/registry/pods/openstack/keepalived-c7t57; range_end:; response_count:1; response_revision:5277; }","duration":"178.170311ms","start":"2026-09-09T01:17:15.244455Z","end":"2026-09-09T01:17:15.422626Z","steps":["trace[1868130388] 'agreement among raft nodes before linearized reading' (duration: 177.773809ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:17:15.60598Z","caller":"traceutil/trace.go:171","msg":"trace[55546686] linearizableReadLoop","detail":"{readStateIndex:5481; appliedIndex:5480; }","duration":"139.348971ms","start":"2026-09-09T01:17:15.466608Z","end":"2026-09-09T01:17:15.605957Z","steps":["trace[55546686] 'read index received' (duration: 139.185696ms)","trace[55546686] 'applied index is now lower than readState.Index' (duration: 162.115µs)"],"step_count":2} {"level":"info","ts":"2026-09-09T01:17:15.606017Z","caller":"traceutil/trace.go:171","msg":"trace[1328270989] transaction","detail":"{read_only:false; response_revision:5278; number_of_response:1; }","duration":"172.942438ms","start":"2026-09-09T01:17:15.433055Z","end":"2026-09-09T01:17:15.605997Z","steps":["trace[1328270989] 'process raft request' (duration: 172.748331ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:17:15.606181Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.554117ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:529"} {"level":"info","ts":"2026-09-09T01:17:15.606234Z","caller":"traceutil/trace.go:171","msg":"trace[255349918] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:5278; }","duration":"139.691181ms","start":"2026-09-09T01:17:15.466531Z","end":"2026-09-09T01:17:15.606222Z","steps":["trace[255349918] 'agreement among raft nodes before linearized reading' (duration: 139.516936ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:17:31.354511Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.703997ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-09-09T01:17:31.354804Z","caller":"traceutil/trace.go:171","msg":"trace[271033225] transaction","detail":"{read_only:false; response_revision:5563; number_of_response:1; }","duration":"149.711635ms","start":"2026-09-09T01:17:31.205045Z","end":"2026-09-09T01:17:31.354757Z","steps":["trace[271033225] 'process raft request' (duration: 19.607975ms)","trace[271033225] 'compare' (duration: 129.491181ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T01:17:31.354905Z","caller":"traceutil/trace.go:171","msg":"trace[1774951914] transaction","detail":"{read_only:false; response_revision:5564; number_of_response:1; }","duration":"148.980564ms","start":"2026-09-09T01:17:31.205896Z","end":"2026-09-09T01:17:31.354877Z","steps":["trace[1774951914] 'process raft request' (duration: 148.744777ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:17:31.355339Z","caller":"traceutil/trace.go:171","msg":"trace[1545179384] transaction","detail":"{read_only:false; response_revision:5565; number_of_response:1; }","duration":"138.822015ms","start":"2026-09-09T01:17:31.216499Z","end":"2026-09-09T01:17:31.355321Z","steps":["trace[1545179384] 'process raft request' (duration: 138.219408ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:17:32.364612Z","caller":"traceutil/trace.go:171","msg":"trace[942466197] transaction","detail":"{read_only:false; response_revision:5566; number_of_response:1; }","duration":"732.546076ms","start":"2026-09-09T01:17:31.632043Z","end":"2026-09-09T01:17:32.364589Z","steps":["trace[942466197] 'process raft request' (duration: 732.407362ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:17:32.364744Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T01:17:31.632022Z","time spent":"732.651938ms","remote":"127.0.0.1:51576","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8038,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-09T01:17:32.379146Z","caller":"traceutil/trace.go:171","msg":"trace[1230832017] linearizableReadLoop","detail":"{readStateIndex:5779; appliedIndex:5777; }","duration":"430.169399ms","start":"2026-09-09T01:17:31.948949Z","end":"2026-09-09T01:17:32.379118Z","steps":["trace[1230832017] 'read index received' (duration: 415.52918ms)","trace[1230832017] 'applied index is now lower than readState.Index' (duration: 14.638529ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T01:17:32.379241Z","caller":"traceutil/trace.go:171","msg":"trace[1434962395] transaction","detail":"{read_only:false; response_revision:5567; number_of_response:1; }","duration":"488.209862ms","start":"2026-09-09T01:17:31.891006Z","end":"2026-09-09T01:17:32.379216Z","steps":["trace[1434962395] 'process raft request' (duration: 487.976485ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:17:32.379412Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T01:17:31.89098Z","time spent":"488.335325ms","remote":"127.0.0.1:51640","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-09T01:17:32.379491Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"430.521098ms","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-09T01:17:32.379578Z","caller":"traceutil/trace.go:171","msg":"trace[1493816248] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5567; }","duration":"430.659242ms","start":"2026-09-09T01:17:31.948897Z","end":"2026-09-09T01:17:32.379556Z","steps":["trace[1493816248] 'agreement among raft nodes before linearized reading' (duration: 430.350244ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:17:32.379623Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T01:17:31.948874Z","time spent":"430.741416ms","remote":"127.0.0.1:51640","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-09-09T01:17:32.39833Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"415.287902ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-09-09T01:17:32.398414Z","caller":"traceutil/trace.go:171","msg":"trace[2106159301] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5571; }","duration":"415.708994ms","start":"2026-09-09T01:17:31.982684Z","end":"2026-09-09T01:17:32.398393Z","steps":["trace[2106159301] 'agreement among raft nodes before linearized reading' (duration: 414.719144ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:17:32.398483Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T01:17:31.982651Z","time spent":"415.819177ms","remote":"127.0.0.1:51640","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-09-09T01:17:32.399609Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.916336ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-09-09T01:17:32.399697Z","caller":"traceutil/trace.go:171","msg":"trace[1888810305] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5571; }","duration":"252.044279ms","start":"2026-09-09T01:17:32.147629Z","end":"2026-09-09T01:17:32.399673Z","steps":["trace[1888810305] 'agreement among raft nodes before linearized reading' (duration: 249.972258ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:17:32.399777Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"414.056476ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-09-09T01:17:32.399842Z","caller":"traceutil/trace.go:171","msg":"trace[1800943356] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:5571; }","duration":"414.167499ms","start":"2026-09-09T01:17:31.985656Z","end":"2026-09-09T01:17:32.399824Z","steps":["trace[1800943356] 'agreement among raft nodes before linearized reading' (duration: 412.066307ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:17:32.399886Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T01:17:31.98563Z","time spent":"414.243181ms","remote":"127.0.0.1:51640","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":580,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-09-09T01:17:51.084804Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.543532ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-09-09T01:17:51.084986Z","caller":"traceutil/trace.go:171","msg":"trace[1814088678] linearizableReadLoop","detail":"{readStateIndex:6109; appliedIndex:6108; }","duration":"108.720482ms","start":"2026-09-09T01:17:50.97624Z","end":"2026-09-09T01:17:51.08496Z","steps":["trace[1814088678] 'read index received' (duration: 6.909522ms)","trace[1814088678] 'applied index is now lower than readState.Index' (duration: 101.80881ms)"],"step_count":2} {"level":"warn","ts":"2026-09-09T01:17:51.085341Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.039631ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-09T01:17:51.085484Z","caller":"traceutil/trace.go:171","msg":"trace[1114806512] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:5889; }","duration":"109.252267ms","start":"2026-09-09T01:17:50.976172Z","end":"2026-09-09T01:17:51.085424Z","steps":["trace[1114806512] 'agreement among raft nodes before linearized reading' (duration: 108.894477ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:17:52.083888Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2990} {"level":"info","ts":"2026-09-09T01:17:52.165151Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2990,"took":"79.044906ms","hash":2121882832,"current-db-size-bytes":56176640,"current-db-size":"56 MB","current-db-size-in-use-bytes":32514048,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-09-09T01:17:52.16523Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2121882832,"revision":2990,"compact-revision":-1} {"level":"info","ts":"2026-09-09T01:22:52.091038Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5897} {"level":"info","ts":"2026-09-09T01:22:52.180922Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5897,"took":"87.508888ms","hash":956192263,"current-db-size-bytes":56176640,"current-db-size":"56 MB","current-db-size-in-use-bytes":26730496,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-09-09T01:22:52.180987Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":956192263,"revision":5897,"compact-revision":2990} {"level":"info","ts":"2026-09-09T01:23:51.142912Z","caller":"traceutil/trace.go:171","msg":"trace[1147732296] linearizableReadLoop","detail":"{readStateIndex:8895; appliedIndex:8894; }","duration":"108.992969ms","start":"2026-09-09T01:23:51.033885Z","end":"2026-09-09T01:23:51.142878Z","steps":["trace[1147732296] 'read index received' (duration: 108.713061ms)","trace[1147732296] 'applied index is now lower than readState.Index' (duration: 278.828µs)"],"step_count":2} {"level":"info","ts":"2026-09-09T01:23:51.14298Z","caller":"traceutil/trace.go:171","msg":"trace[29969018] transaction","detail":"{read_only:false; response_revision:8588; number_of_response:1; }","duration":"119.627251ms","start":"2026-09-09T01:23:51.023313Z","end":"2026-09-09T01:23:51.14294Z","steps":["trace[29969018] 'process raft request' (duration: 119.358983ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:23:51.143135Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.218935ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/acme.cert-manager.io/orders/\" range_end:\"/registry/acme.cert-manager.io/orders0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-09T01:23:51.143204Z","caller":"traceutil/trace.go:171","msg":"trace[631567067] range","detail":"{range_begin:/registry/acme.cert-manager.io/orders/; range_end:/registry/acme.cert-manager.io/orders0; response_count:0; response_revision:8588; }","duration":"109.36944ms","start":"2026-09-09T01:23:51.033814Z","end":"2026-09-09T01:23:51.143184Z","steps":["trace[631567067] 'agreement among raft nodes before linearized reading' (duration: 109.225116ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:23:52.747825Z","caller":"traceutil/trace.go:171","msg":"trace[1423822113] linearizableReadLoop","detail":"{readStateIndex:8898; appliedIndex:8897; }","duration":"156.355899ms","start":"2026-09-09T01:23:52.591432Z","end":"2026-09-09T01:23:52.747788Z","steps":["trace[1423822113] 'read index received' (duration: 156.073071ms)","trace[1423822113] 'applied index is now lower than readState.Index' (duration: 281.128µs)"],"step_count":2} {"level":"info","ts":"2026-09-09T01:23:52.74795Z","caller":"traceutil/trace.go:171","msg":"trace[1920312721] transaction","detail":"{read_only:false; response_revision:8591; number_of_response:1; }","duration":"157.264416ms","start":"2026-09-09T01:23:52.590649Z","end":"2026-09-09T01:23:52.747913Z","steps":["trace[1920312721] 'process raft request' (duration: 156.824283ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:23:52.748128Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.657779ms","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-09T01:23:52.74817Z","caller":"traceutil/trace.go:171","msg":"trace[1059108515] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8591; }","duration":"156.760952ms","start":"2026-09-09T01:23:52.591397Z","end":"2026-09-09T01:23:52.748158Z","steps":["trace[1059108515] 'agreement among raft nodes before linearized reading' (duration: 156.550145ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:23:52.748582Z","caller":"traceutil/trace.go:171","msg":"trace[336512301] transaction","detail":"{read_only:false; response_revision:8592; number_of_response:1; }","duration":"156.455123ms","start":"2026-09-09T01:23:52.592076Z","end":"2026-09-09T01:23:52.748531Z","steps":["trace[336512301] 'process raft request' (duration: 156.240667ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:23:52.748689Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.033603ms","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-09T01:23:52.748756Z","caller":"traceutil/trace.go:171","msg":"trace[1291378629] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8592; }","duration":"151.232449ms","start":"2026-09-09T01:23:52.597507Z","end":"2026-09-09T01:23:52.748739Z","steps":["trace[1291378629] 'agreement among raft nodes before linearized reading' (duration: 150.967461ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:24:05.322797Z","caller":"traceutil/trace.go:171","msg":"trace[1570051944] transaction","detail":"{read_only:false; response_revision:8659; number_of_response:1; }","duration":"114.258193ms","start":"2026-09-09T01:24:05.208508Z","end":"2026-09-09T01:24:05.322767Z","steps":["trace[1570051944] 'process raft request' (duration: 114.029226ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:24:05.729366Z","caller":"traceutil/trace.go:171","msg":"trace[1419099188] linearizableReadLoop","detail":"{readStateIndex:8970; appliedIndex:8969; }","duration":"194.077357ms","start":"2026-09-09T01:24:05.535249Z","end":"2026-09-09T01:24:05.729326Z","steps":["trace[1419099188] 'read index received' (duration: 185.357821ms)","trace[1419099188] 'applied index is now lower than readState.Index' (duration: 8.717656ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T01:24:05.7294Z","caller":"traceutil/trace.go:171","msg":"trace[1616400504] transaction","detail":"{read_only:false; response_revision:8661; number_of_response:1; }","duration":"400.744501ms","start":"2026-09-09T01:24:05.328619Z","end":"2026-09-09T01:24:05.729363Z","steps":["trace[1616400504] 'process raft request' (duration: 392.042426ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:24:05.729617Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T01:24:05.328563Z","time spent":"400.915366ms","remote":"127.0.0.1:51640","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-09T01:24:05.72968Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.439787ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.242\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-09-09T01:24:05.729748Z","caller":"traceutil/trace.go:171","msg":"trace[1847314202] range","detail":"{range_begin:/registry/masterleases/162.253.55.242; range_end:; response_count:1; response_revision:8661; }","duration":"194.570301ms","start":"2026-09-09T01:24:05.535159Z","end":"2026-09-09T01:24:05.72973Z","steps":["trace[1847314202] 'agreement among raft nodes before linearized reading' (duration: 194.305653ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:24:06.346378Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.138949ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-c7t57\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-09-09T01:24:06.346485Z","caller":"traceutil/trace.go:171","msg":"trace[285918719] range","detail":"{range_begin:/registry/pods/openstack/keepalived-c7t57; range_end:; response_count:1; response_revision:8663; }","duration":"132.325734ms","start":"2026-09-09T01:24:06.214138Z","end":"2026-09-09T01:24:06.346464Z","steps":["trace[285918719] 'range keys from in-memory index tree' (duration: 131.88547ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:24:34.534259Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"403.805065ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-09T01:24:34.534367Z","caller":"traceutil/trace.go:171","msg":"trace[2053398585] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8863; }","duration":"403.98892ms","start":"2026-09-09T01:24:34.13036Z","end":"2026-09-09T01:24:34.534349Z","steps":["trace[2053398585] 'range keys from in-memory index tree' (duration: 403.719992ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:24:34.534407Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T01:24:34.13033Z","time spent":"404.064152ms","remote":"127.0.0.1:51358","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-09T01:24:34.534962Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.321291ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-09T01:24:34.535091Z","caller":"traceutil/trace.go:171","msg":"trace[1507127461] linearizableReadLoop","detail":"{readStateIndex:9181; appliedIndex:9180; }","duration":"195.507394ms","start":"2026-09-09T01:24:34.339563Z","end":"2026-09-09T01:24:34.535071Z","steps":["trace[1507127461] 'read index received' (duration: 47.482µs)","trace[1507127461] 'applied index is now lower than readState.Index' (duration: 195.458441ms)"],"step_count":2} {"level":"warn","ts":"2026-09-09T01:24:34.535224Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.672678ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-09T01:24:34.535288Z","caller":"traceutil/trace.go:171","msg":"trace[1728908010] range","detail":"{range_begin:/registry/services/endpoints/; range_end:/registry/services/endpoints0; response_count:0; response_revision:8864; }","duration":"195.766291ms","start":"2026-09-09T01:24:34.339486Z","end":"2026-09-09T01:24:34.535252Z","steps":["trace[1728908010] 'agreement among raft nodes before linearized reading' (duration: 195.642317ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:24:34.535218Z","caller":"traceutil/trace.go:171","msg":"trace[416845024] transaction","detail":"{read_only:false; response_revision:8864; number_of_response:1; }","duration":"311.275465ms","start":"2026-09-09T01:24:34.223895Z","end":"2026-09-09T01:24:34.53517Z","steps":["trace[416845024] 'process raft request' (duration: 70.631685ms)","trace[416845024] 'compare' (duration: 240.182997ms)"],"step_count":2} {"level":"warn","ts":"2026-09-09T01:24:34.535459Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T01:24:34.223844Z","time spent":"311.497111ms","remote":"127.0.0.1:51640","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-09T01:24:41.669202Z","caller":"traceutil/trace.go:171","msg":"trace[1901853605] transaction","detail":"{read_only:false; response_revision:8906; number_of_response:1; }","duration":"141.442846ms","start":"2026-09-09T01:24:41.527726Z","end":"2026-09-09T01:24:41.669169Z","steps":["trace[1901853605] 'process raft request' (duration: 141.26826ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:24:49.07071Z","caller":"traceutil/trace.go:171","msg":"trace[915494060] transaction","detail":"{read_only:false; number_of_response:1; response_revision:8980; }","duration":"162.3391ms","start":"2026-09-09T01:24:48.908296Z","end":"2026-09-09T01:24:49.070635Z","steps":["trace[915494060] 'process raft request' (duration: 64.081023ms)","trace[915494060] 'compare' (duration: 97.941918ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T01:24:49.094024Z","caller":"traceutil/trace.go:171","msg":"trace[724036893] linearizableReadLoop","detail":"{readStateIndex:9304; appliedIndex:9302; }","duration":"181.096461ms","start":"2026-09-09T01:24:48.912903Z","end":"2026-09-09T01:24:49.094Z","steps":["trace[724036893] 'read index received' (duration: 59.487299ms)","trace[724036893] 'applied index is now lower than readState.Index' (duration: 121.607072ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T01:24:49.09408Z","caller":"traceutil/trace.go:171","msg":"trace[1864390887] transaction","detail":"{read_only:false; number_of_response:1; response_revision:8980; }","duration":"182.219504ms","start":"2026-09-09T01:24:48.911821Z","end":"2026-09-09T01:24:49.094041Z","steps":["trace[1864390887] 'process raft request' (duration: 182.07421ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:24:49.094344Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.517556ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/openstack/rook-ceph-detect-version-rvkb8\" ","response":"range_response_count:1 size:2803"} {"level":"info","ts":"2026-09-09T01:24:49.094423Z","caller":"traceutil/trace.go:171","msg":"trace[1258333162] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/openstack/rook-ceph-detect-version-rvkb8; range_end:; response_count:1; response_revision:8980; }","duration":"179.635038ms","start":"2026-09-09T01:24:48.914762Z","end":"2026-09-09T01:24:49.094397Z","steps":["trace[1258333162] 'agreement among raft nodes before linearized reading' (duration: 179.379501ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:24:49.094725Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.800972ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rook-ceph-detect-version-rvkb8\" ","response":"range_response_count:1 size:4668"} {"level":"warn","ts":"2026-09-09T01:24:49.094778Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.104993ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/ingress-nginx/ingress-nginx-controller\" ","response":"range_response_count:1 size:1986"} {"level":"info","ts":"2026-09-09T01:24:49.09481Z","caller":"traceutil/trace.go:171","msg":"trace[239682418] range","detail":"{range_begin:/registry/pods/openstack/rook-ceph-detect-version-rvkb8; range_end:; response_count:1; response_revision:8980; }","duration":"181.941976ms","start":"2026-09-09T01:24:48.912849Z","end":"2026-09-09T01:24:49.094791Z","steps":["trace[239682418] 'agreement among raft nodes before linearized reading' (duration: 181.267446ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:24:49.094829Z","caller":"traceutil/trace.go:171","msg":"trace[1063224487] range","detail":"{range_begin:/registry/services/specs/ingress-nginx/ingress-nginx-controller; range_end:; response_count:1; response_revision:8980; }","duration":"144.210037ms","start":"2026-09-09T01:24:48.950602Z","end":"2026-09-09T01:24:49.094812Z","steps":["trace[1063224487] 'agreement among raft nodes before linearized reading' (duration: 144.019922ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:24:49.30366Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-0000000000002460.wal"} {"level":"info","ts":"2026-09-09T01:24:49.333921Z","caller":"traceutil/trace.go:171","msg":"trace[954942206] transaction","detail":"{read_only:false; response_revision:8988; number_of_response:1; }","duration":"116.674849ms","start":"2026-09-09T01:24:49.217212Z","end":"2026-09-09T01:24:49.333887Z","steps":["trace[954942206] 'process raft request' (duration: 93.699363ms)","trace[954942206] 'compare' (duration: 22.554413ms)"],"step_count":2} {"level":"warn","ts":"2026-09-09T01:24:49.334296Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.736989ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rook-ceph-detect-version-rvkb8\" ","response":"range_response_count:1 size:4653"} {"level":"info","ts":"2026-09-09T01:24:49.334436Z","caller":"traceutil/trace.go:171","msg":"trace[243394259] range","detail":"{range_begin:/registry/pods/openstack/rook-ceph-detect-version-rvkb8; range_end:; response_count:1; response_revision:8989; }","duration":"117.903684ms","start":"2026-09-09T01:24:49.216509Z","end":"2026-09-09T01:24:49.334413Z","steps":["trace[243394259] 'agreement among raft nodes before linearized reading' (duration: 117.470661ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:24:49.334703Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.037621ms","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-09T01:24:49.334756Z","caller":"traceutil/trace.go:171","msg":"trace[611813882] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8989; }","duration":"113.121532ms","start":"2026-09-09T01:24:49.221622Z","end":"2026-09-09T01:24:49.334744Z","steps":["trace[611813882] 'agreement among raft nodes before linearized reading' (duration: 112.986948ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:24:49.334939Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.064259ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-09-09T01:24:49.334978Z","caller":"traceutil/trace.go:171","msg":"trace[1423622729] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8989; }","duration":"103.124931ms","start":"2026-09-09T01:24:49.231841Z","end":"2026-09-09T01:24:49.334966Z","steps":["trace[1423622729] 'agreement among raft nodes before linearized reading' (duration: 103.034637ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:24:50.064243Z","caller":"traceutil/trace.go:171","msg":"trace[1849484389] linearizableReadLoop","detail":"{readStateIndex:9318; appliedIndex:9316; }","duration":"159.047222ms","start":"2026-09-09T01:24:49.905171Z","end":"2026-09-09T01:24:50.064218Z","steps":["trace[1849484389] 'read index received' (duration: 32.726031ms)","trace[1849484389] 'applied index is now lower than readState.Index' (duration: 126.319831ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T01:24:50.064353Z","caller":"traceutil/trace.go:171","msg":"trace[184992436] transaction","detail":"{read_only:false; response_revision:8994; number_of_response:1; }","duration":"159.31443ms","start":"2026-09-09T01:24:49.905006Z","end":"2026-09-09T01:24:50.06432Z","steps":["trace[184992436] 'process raft request' (duration: 159.047522ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:24:50.064706Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.512497ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rook-ceph-detect-version-rvkb8\" ","response":"range_response_count:1 size:4653"} {"level":"info","ts":"2026-09-09T01:24:50.0648Z","caller":"traceutil/trace.go:171","msg":"trace[1062799167] range","detail":"{range_begin:/registry/pods/openstack/rook-ceph-detect-version-rvkb8; range_end:; response_count:1; response_revision:8994; }","duration":"159.63435ms","start":"2026-09-09T01:24:49.905143Z","end":"2026-09-09T01:24:50.064777Z","steps":["trace[1062799167] 'agreement among raft nodes before linearized reading' (duration: 159.229348ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:26:03.87833Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"cc70bec9404419f6","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-09-09T01:26:03.887171Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-09-09T01:26:03.887477Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"warn","ts":"2026-09-09T01:26:12.030253Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.431937ms","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-09T01:26:12.030357Z","caller":"traceutil/trace.go:171","msg":"trace[1884922797] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:9702; }","duration":"137.606263ms","start":"2026-09-09T01:26:11.892729Z","end":"2026-09-09T01:26:12.030335Z","steps":["trace[1884922797] 'range keys from in-memory index tree' (duration: 137.210801ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:26:12.030396Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.490569ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"warn","ts":"2026-09-09T01:26:12.030411Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.553441ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-09-09T01:26:12.030504Z","caller":"traceutil/trace.go:171","msg":"trace[562663225] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:9702; }","duration":"105.668585ms","start":"2026-09-09T01:26:11.924809Z","end":"2026-09-09T01:26:12.030478Z","steps":["trace[562663225] 'range keys from in-memory index tree' (duration: 105.278533ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:26:12.030522Z","caller":"traceutil/trace.go:171","msg":"trace[523740762] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:9702; }","duration":"105.719957ms","start":"2026-09-09T01:26:11.924779Z","end":"2026-09-09T01:26:12.030499Z","steps":["trace[523740762] 'range keys from in-memory index tree' (duration: 105.303414ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:26:12.298452Z","caller":"traceutil/trace.go:171","msg":"trace[98598152] transaction","detail":"{read_only:false; response_revision:9704; number_of_response:1; }","duration":"262.573565ms","start":"2026-09-09T01:26:12.035847Z","end":"2026-09-09T01:26:12.298421Z","steps":["trace[98598152] 'process raft request' (duration: 262.347279ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:26:12.29883Z","caller":"traceutil/trace.go:171","msg":"trace[789635616] transaction","detail":"{read_only:false; response_revision:9705; number_of_response:1; }","duration":"262.447801ms","start":"2026-09-09T01:26:12.036346Z","end":"2026-09-09T01:26:12.298794Z","steps":["trace[789635616] 'process raft request' (duration: 262.007428ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:26:12.299067Z","caller":"traceutil/trace.go:171","msg":"trace[552744036] transaction","detail":"{read_only:false; response_revision:9706; number_of_response:1; }","duration":"262.624575ms","start":"2026-09-09T01:26:12.03643Z","end":"2026-09-09T01:26:12.299055Z","steps":["trace[552744036] 'process raft request' (duration: 262.273305ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:26:12.299166Z","caller":"traceutil/trace.go:171","msg":"trace[745821992] linearizableReadLoop","detail":"{readStateIndex:10053; appliedIndex:10049; }","duration":"262.143772ms","start":"2026-09-09T01:26:12.036913Z","end":"2026-09-09T01:26:12.299057Z","steps":["trace[745821992] 'read index received' (duration: 34.452592ms)","trace[745821992] 'applied index is now lower than readState.Index' (duration: 227.686869ms)"],"step_count":2} {"level":"warn","ts":"2026-09-09T01:26:12.299514Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.571926ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:20136"} {"level":"info","ts":"2026-09-09T01:26:12.299592Z","caller":"traceutil/trace.go:171","msg":"trace[188277551] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:9706; }","duration":"262.679728ms","start":"2026-09-09T01:26:12.036881Z","end":"2026-09-09T01:26:12.29956Z","steps":["trace[188277551] 'agreement among raft nodes before linearized reading' (duration: 262.439881ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:26:12.330015Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.398178ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-09T01:26:12.330129Z","caller":"traceutil/trace.go:171","msg":"trace[112929123] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:9707; }","duration":"198.583495ms","start":"2026-09-09T01:26:12.131517Z","end":"2026-09-09T01:26:12.330101Z","steps":["trace[112929123] 'agreement among raft nodes before linearized reading' (duration: 198.377578ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:26:12.330153Z","caller":"traceutil/trace.go:171","msg":"trace[1059760819] transaction","detail":"{read_only:false; response_revision:9707; number_of_response:1; }","duration":"254.191299ms","start":"2026-09-09T01:26:12.075927Z","end":"2026-09-09T01:26:12.330118Z","steps":["trace[1059760819] 'process raft request' (duration: 253.742585ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:27:52.099146Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8041} {"level":"info","ts":"2026-09-09T01:27:52.174295Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8041,"took":"72.001295ms","hash":2425408504,"current-db-size-bytes":56176640,"current-db-size":"56 MB","current-db-size-in-use-bytes":28381184,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-09-09T01:27:52.174375Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2425408504,"revision":8041,"compact-revision":5897} {"level":"info","ts":"2026-09-09T01:29:52.147082Z","caller":"traceutil/trace.go:171","msg":"trace[654715452] linearizableReadLoop","detail":"{readStateIndex:12031; appliedIndex:12030; }","duration":"121.616093ms","start":"2026-09-09T01:29:52.025437Z","end":"2026-09-09T01:29:52.147053Z","steps":["trace[654715452] 'read index received' (duration: 121.380406ms)","trace[654715452] 'applied index is now lower than readState.Index' (duration: 234.027µs)"],"step_count":2} {"level":"info","ts":"2026-09-09T01:29:52.14713Z","caller":"traceutil/trace.go:171","msg":"trace[871912977] transaction","detail":"{read_only:false; response_revision:11624; number_of_response:1; }","duration":"126.435244ms","start":"2026-09-09T01:29:52.020672Z","end":"2026-09-09T01:29:52.147107Z","steps":["trace[871912977] 'process raft request' (duration: 126.201618ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:29:52.147385Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.918061ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-09-09T01:29:52.147442Z","caller":"traceutil/trace.go:171","msg":"trace[21255877] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:11624; }","duration":"122.047276ms","start":"2026-09-09T01:29:52.025379Z","end":"2026-09-09T01:29:52.147426Z","steps":["trace[21255877] 'agreement among raft nodes before linearized reading' (duration: 121.822349ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:29:52.279554Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.075321ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-09T01:29:52.279776Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.890546ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-09T01:29:52.27989Z","caller":"traceutil/trace.go:171","msg":"trace[1169716782] transaction","detail":"{read_only:false; response_revision:11625; number_of_response:1; }","duration":"127.713611ms","start":"2026-09-09T01:29:52.152143Z","end":"2026-09-09T01:29:52.279856Z","steps":["trace[1169716782] 'process raft request' (duration: 13.64734ms)","trace[1169716782] 'compare' (duration: 113.717401ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T01:29:52.279884Z","caller":"traceutil/trace.go:171","msg":"trace[636843343] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:11624; }","duration":"131.36963ms","start":"2026-09-09T01:29:52.148385Z","end":"2026-09-09T01:29:52.279755Z","steps":["trace[636843343] 'range keys from in-memory index tree' (duration: 131.016569ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:32:52.107172Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10498} {"level":"info","ts":"2026-09-09T01:32:52.196971Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10498,"took":"86.875541ms","hash":51241678,"current-db-size-bytes":56176640,"current-db-size":"56 MB","current-db-size-in-use-bytes":28049408,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-09-09T01:32:52.197098Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":51241678,"revision":10498,"compact-revision":8041} {"level":"info","ts":"2026-09-09T01:35:50.963792Z","caller":"traceutil/trace.go:171","msg":"trace[1736133468] transaction","detail":"{read_only:false; response_revision:14059; number_of_response:1; }","duration":"185.633842ms","start":"2026-09-09T01:35:50.778131Z","end":"2026-09-09T01:35:50.963765Z","steps":["trace[1736133468] 'process raft request' (duration: 185.469397ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:35:54.19663Z","caller":"traceutil/trace.go:171","msg":"trace[877046634] transaction","detail":"{read_only:false; response_revision:14083; number_of_response:1; }","duration":"120.879681ms","start":"2026-09-09T01:35:54.075704Z","end":"2026-09-09T01:35:54.196584Z","steps":["trace[877046634] 'process raft request' (duration: 120.676684ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:36:24.462471Z","caller":"traceutil/trace.go:171","msg":"trace[105970386] transaction","detail":"{read_only:false; response_revision:14237; number_of_response:1; }","duration":"128.504503ms","start":"2026-09-09T01:36:24.333918Z","end":"2026-09-09T01:36:24.462423Z","steps":["trace[105970386] 'process raft request' (duration: 128.295687ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:36:32.807584Z","caller":"traceutil/trace.go:171","msg":"trace[992851168] transaction","detail":"{read_only:false; response_revision:14295; number_of_response:1; }","duration":"108.406113ms","start":"2026-09-09T01:36:32.699122Z","end":"2026-09-09T01:36:32.807529Z","steps":["trace[992851168] 'process raft request' (duration: 95.212175ms)","trace[992851168] 'compare' (duration: 13.047544ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T01:36:33.447403Z","caller":"traceutil/trace.go:171","msg":"trace[59075711] transaction","detail":"{read_only:false; response_revision:14296; number_of_response:1; }","duration":"280.987761ms","start":"2026-09-09T01:36:33.166377Z","end":"2026-09-09T01:36:33.447365Z","steps":["trace[59075711] 'process raft request' (duration: 280.426204ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:36:33.643783Z","caller":"traceutil/trace.go:171","msg":"trace[408568360] transaction","detail":"{read_only:false; response_revision:14297; number_of_response:1; }","duration":"165.85647ms","start":"2026-09-09T01:36:33.477883Z","end":"2026-09-09T01:36:33.643739Z","steps":["trace[408568360] 'process raft request' (duration: 151.078077ms)","trace[408568360] 'compare' (duration: 14.126115ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T01:37:52.11637Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12814} {"level":"info","ts":"2026-09-09T01:37:52.21775Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12814,"took":"96.519693ms","hash":2803821162,"current-db-size-bytes":56176640,"current-db-size":"56 MB","current-db-size-in-use-bytes":30937088,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-09-09T01:37:52.217852Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2803821162,"revision":12814,"compact-revision":10498} {"level":"info","ts":"2026-09-09T01:38:28.562183Z","caller":"traceutil/trace.go:171","msg":"trace[56056515] linearizableReadLoop","detail":"{readStateIndex:16115; appliedIndex:16114; }","duration":"230.506369ms","start":"2026-09-09T01:38:28.331655Z","end":"2026-09-09T01:38:28.562162Z","steps":["trace[56056515] 'read index received' (duration: 230.221531ms)","trace[56056515] 'applied index is now lower than readState.Index' (duration: 283.778µs)"],"step_count":2} {"level":"info","ts":"2026-09-09T01:38:28.562532Z","caller":"traceutil/trace.go:171","msg":"trace[1599360669] transaction","detail":"{read_only:false; response_revision:15575; number_of_response:1; }","duration":"238.567804ms","start":"2026-09-09T01:38:28.32395Z","end":"2026-09-09T01:38:28.562518Z","steps":["trace[1599360669] 'process raft request' (duration: 238.024259ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:38:28.562946Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.287803ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-09-09T01:38:28.563017Z","caller":"traceutil/trace.go:171","msg":"trace[1382267370] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:15575; }","duration":"231.429596ms","start":"2026-09-09T01:38:28.331577Z","end":"2026-09-09T01:38:28.563007Z","steps":["trace[1382267370] 'agreement among raft nodes before linearized reading' (duration: 231.234101ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:42:52.123451Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":15385} {"level":"info","ts":"2026-09-09T01:42:52.242101Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":15385,"took":"113.808302ms","hash":3100361966,"current-db-size-bytes":56176640,"current-db-size":"56 MB","current-db-size-in-use-bytes":31494144,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-09-09T01:42:52.242222Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3100361966,"revision":15385,"compact-revision":12814} {"level":"info","ts":"2026-09-09T01:44:01.607929Z","caller":"traceutil/trace.go:171","msg":"trace[1076588200] transaction","detail":"{read_only:false; response_revision:18008; number_of_response:1; }","duration":"148.077761ms","start":"2026-09-09T01:44:01.459822Z","end":"2026-09-09T01:44:01.607899Z","steps":["trace[1076588200] 'process raft request' (duration: 147.859875ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:44:01.609547Z","caller":"traceutil/trace.go:171","msg":"trace[821131119] transaction","detail":"{read_only:false; response_revision:18009; number_of_response:1; }","duration":"147.788813ms","start":"2026-09-09T01:44:01.461708Z","end":"2026-09-09T01:44:01.609497Z","steps":["trace[821131119] 'process raft request' (duration: 147.35128ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:44:05.74175Z","caller":"traceutil/trace.go:171","msg":"trace[995027928] linearizableReadLoop","detail":"{readStateIndex:18711; appliedIndex:18709; }","duration":"110.472076ms","start":"2026-09-09T01:44:05.63125Z","end":"2026-09-09T01:44:05.741722Z","steps":["trace[995027928] 'read index received' (duration: 53.783921ms)","trace[995027928] 'applied index is now lower than readState.Index' (duration: 56.686935ms)"],"step_count":2} {"level":"warn","ts":"2026-09-09T01:44:05.741971Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.696602ms","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-09T01:44:05.742033Z","caller":"traceutil/trace.go:171","msg":"trace[1930117369] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:18087; }","duration":"110.801445ms","start":"2026-09-09T01:44:05.631207Z","end":"2026-09-09T01:44:05.742008Z","steps":["trace[1930117369] 'agreement among raft nodes before linearized reading' (duration: 110.652991ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:44:05.742306Z","caller":"traceutil/trace.go:171","msg":"trace[838908532] transaction","detail":"{read_only:false; response_revision:18087; number_of_response:1; }","duration":"114.512855ms","start":"2026-09-09T01:44:05.62778Z","end":"2026-09-09T01:44:05.742293Z","steps":["trace[838908532] 'process raft request' (duration: 113.813904ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:44:06.305559Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.226744ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-09-09T01:44:06.305715Z","caller":"traceutil/trace.go:171","msg":"trace[867286616] range","detail":"{range_begin:/registry/replicasets/; range_end:/registry/replicasets0; response_count:0; response_revision:18090; }","duration":"104.46323ms","start":"2026-09-09T01:44:06.20122Z","end":"2026-09-09T01:44:06.305683Z","steps":["trace[867286616] 'count revisions from in-memory index tree' (duration: 104.072279ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:44:07.235431Z","caller":"traceutil/trace.go:171","msg":"trace[828621337] linearizableReadLoop","detail":"{readStateIndex:18724; appliedIndex:18723; }","duration":"342.156324ms","start":"2026-09-09T01:44:06.893236Z","end":"2026-09-09T01:44:07.235393Z","steps":["trace[828621337] 'read index received' (duration: 305.376643ms)","trace[828621337] 'applied index is now lower than readState.Index' (duration: 36.778121ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T01:44:07.235657Z","caller":"traceutil/trace.go:171","msg":"trace[987401563] transaction","detail":"{read_only:false; response_revision:18100; number_of_response:1; }","duration":"348.027796ms","start":"2026-09-09T01:44:06.887605Z","end":"2026-09-09T01:44:07.235633Z","steps":["trace[987401563] 'process raft request' (duration: 311.07495ms)","trace[987401563] 'compare' (duration: 36.235625ms)"],"step_count":2} {"level":"warn","ts":"2026-09-09T01:44:07.23596Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"342.70561ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:559"} {"level":"info","ts":"2026-09-09T01:44:07.23608Z","caller":"traceutil/trace.go:171","msg":"trace[945305130] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:18100; }","duration":"342.871035ms","start":"2026-09-09T01:44:06.89318Z","end":"2026-09-09T01:44:07.236051Z","steps":["trace[945305130] 'agreement among raft nodes before linearized reading' (duration: 342.562586ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:44:07.236121Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T01:44:06.893156Z","time spent":"342.956476ms","remote":"127.0.0.1:51640","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":582,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-09-09T01:44:07.236144Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.660986ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-09-09T01:44:07.236185Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T01:44:06.887562Z","time spent":"348.14183ms","remote":"127.0.0.1:51640","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-09T01:44:07.236226Z","caller":"traceutil/trace.go:171","msg":"trace[740393585] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:18100; }","duration":"102.782611ms","start":"2026-09-09T01:44:07.133421Z","end":"2026-09-09T01:44:07.236204Z","steps":["trace[740393585] 'agreement among raft nodes before linearized reading' (duration: 102.623575ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:44:07.236401Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.449006ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-09-09T01:44:07.236762Z","caller":"traceutil/trace.go:171","msg":"trace[1040150521] range","detail":"{range_begin:/registry/persistentvolumes/; range_end:/registry/persistentvolumes0; response_count:0; response_revision:18100; }","duration":"272.910599ms","start":"2026-09-09T01:44:06.963828Z","end":"2026-09-09T01:44:07.236738Z","steps":["trace[1040150521] 'agreement among raft nodes before linearized reading' (duration: 271.91879ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:46:29.419215Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"cc70bec9404419f6","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-09-09T01:46:29.430664Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-09-09T01:46:29.430806Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-09-09T01:47:52.134363Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17519} {"level":"info","ts":"2026-09-09T01:47:52.241488Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17519,"took":"101.349109ms","hash":1558284750,"current-db-size-bytes":56176640,"current-db-size":"56 MB","current-db-size-in-use-bytes":33886208,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-09-09T01:47:52.241561Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1558284750,"revision":17519,"compact-revision":15385} {"level":"info","ts":"2026-09-09T01:49:18.897961Z","caller":"traceutil/trace.go:171","msg":"trace[1689025702] transaction","detail":"{read_only:false; response_revision:20538; number_of_response:1; }","duration":"154.4843ms","start":"2026-09-09T01:49:18.743442Z","end":"2026-09-09T01:49:18.897926Z","steps":["trace[1689025702] 'process raft request' (duration: 154.290993ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:49:18.934968Z","caller":"traceutil/trace.go:171","msg":"trace[201557980] transaction","detail":"{read_only:false; response_revision:20539; number_of_response:1; }","duration":"190.721614ms","start":"2026-09-09T01:49:18.744205Z","end":"2026-09-09T01:49:18.934926Z","steps":["trace[201557980] 'process raft request' (duration: 190.441686ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:49:29.327228Z","caller":"traceutil/trace.go:171","msg":"trace[1850189697] linearizableReadLoop","detail":"{readStateIndex:21299; appliedIndex:21298; }","duration":"107.953522ms","start":"2026-09-09T01:49:29.219245Z","end":"2026-09-09T01:49:29.327199Z","steps":["trace[1850189697] 'read index received' (duration: 107.753186ms)","trace[1850189697] 'applied index is now lower than readState.Index' (duration: 199.236µs)"],"step_count":2} {"level":"warn","ts":"2026-09-09T01:49:29.32751Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.237731ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-09-09T01:49:29.327491Z","caller":"traceutil/trace.go:171","msg":"trace[1913838055] transaction","detail":"{read_only:false; response_revision:20592; number_of_response:1; }","duration":"129.435552ms","start":"2026-09-09T01:49:29.198019Z","end":"2026-09-09T01:49:29.327454Z","steps":["trace[1913838055] 'process raft request' (duration: 129.00712ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:49:29.327564Z","caller":"traceutil/trace.go:171","msg":"trace[961993752] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:20592; }","duration":"108.368104ms","start":"2026-09-09T01:49:29.219181Z","end":"2026-09-09T01:49:29.327549Z","steps":["trace[961993752] 'agreement among raft nodes before linearized reading' (duration: 108.169808ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:49:34.922014Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.573445ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-09-09T01:49:34.92211Z","caller":"traceutil/trace.go:171","msg":"trace[1039025472] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:20619; }","duration":"133.729479ms","start":"2026-09-09T01:49:34.788361Z","end":"2026-09-09T01:49:34.922091Z","steps":["trace[1039025472] 'count revisions from in-memory index tree' (duration: 133.456551ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:49:35.308197Z","caller":"traceutil/trace.go:171","msg":"trace[1726381882] transaction","detail":"{read_only:false; response_revision:20622; number_of_response:1; }","duration":"141.383055ms","start":"2026-09-09T01:49:35.166769Z","end":"2026-09-09T01:49:35.308153Z","steps":["trace[1726381882] 'process raft request' (duration: 141.171459ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:49:35.59055Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.883469ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-09T01:49:35.590714Z","caller":"traceutil/trace.go:171","msg":"trace[705130075] linearizableReadLoop","detail":"{readStateIndex:21331; appliedIndex:21330; }","duration":"212.293498ms","start":"2026-09-09T01:49:35.378399Z","end":"2026-09-09T01:49:35.590693Z","steps":["trace[705130075] 'read index received' (duration: 102.182842ms)","trace[705130075] 'applied index is now lower than readState.Index' (duration: 110.108976ms)"],"step_count":2} {"level":"warn","ts":"2026-09-09T01:49:35.590962Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.567526ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:559"} {"level":"info","ts":"2026-09-09T01:49:35.591009Z","caller":"traceutil/trace.go:171","msg":"trace[1818150920] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:20623; }","duration":"212.653629ms","start":"2026-09-09T01:49:35.378339Z","end":"2026-09-09T01:49:35.590993Z","steps":["trace[1818150920] 'agreement among raft nodes before linearized reading' (duration: 212.411282ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:49:35.591254Z","caller":"traceutil/trace.go:171","msg":"trace[1492984119] transaction","detail":"{read_only:false; response_revision:20623; number_of_response:1; }","duration":"234.278374ms","start":"2026-09-09T01:49:35.356931Z","end":"2026-09-09T01:49:35.591209Z","steps":["trace[1492984119] 'process raft request' (duration: 123.637433ms)","trace[1492984119] 'compare' (duration: 109.356333ms)"],"step_count":2} {"level":"warn","ts":"2026-09-09T01:49:35.952496Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.893493ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-09T01:49:35.952805Z","caller":"traceutil/trace.go:171","msg":"trace[64260369] linearizableReadLoop","detail":"{readStateIndex:21333; appliedIndex:21331; }","duration":"318.90743ms","start":"2026-09-09T01:49:35.633879Z","end":"2026-09-09T01:49:35.952786Z","steps":["trace[64260369] 'read index received' (duration: 149.677138ms)","trace[64260369] 'applied index is now lower than readState.Index' (duration: 169.229292ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T01:49:35.952837Z","caller":"traceutil/trace.go:171","msg":"trace[779784159] transaction","detail":"{read_only:false; response_revision:20624; number_of_response:1; }","duration":"481.24584ms","start":"2026-09-09T01:49:35.471561Z","end":"2026-09-09T01:49:35.952806Z","steps":["trace[779784159] 'process raft request' (duration: 311.918064ms)","trace[779784159] 'compare' (duration: 168.619756ms)"],"step_count":2} {"level":"warn","ts":"2026-09-09T01:49:35.952977Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T01:49:35.471532Z","time spent":"481.375264ms","remote":"127.0.0.1:51640","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-09T01:49:35.953175Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.043799ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"warn","ts":"2026-09-09T01:49:35.95327Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"319.370464ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.242\" ","response":"range_response_count:1 size:140"} {"level":"info","ts":"2026-09-09T01:49:35.95332Z","caller":"traceutil/trace.go:171","msg":"trace[628993167] transaction","detail":"{read_only:false; response_revision:20625; number_of_response:1; }","duration":"353.333033ms","start":"2026-09-09T01:49:35.599964Z","end":"2026-09-09T01:49:35.953297Z","steps":["trace[628993167] 'process raft request' (duration: 352.673143ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:49:35.953364Z","caller":"traceutil/trace.go:171","msg":"trace[902651885] range","detail":"{range_begin:/registry/masterleases/162.253.55.242; range_end:; response_count:1; response_revision:20626; }","duration":"319.508939ms","start":"2026-09-09T01:49:35.633832Z","end":"2026-09-09T01:49:35.953341Z","steps":["trace[902651885] 'agreement among raft nodes before linearized reading' (duration: 319.050735ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:49:35.953411Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T01:49:35.633817Z","time spent":"319.582282ms","remote":"127.0.0.1:51412","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":163,"request content":"key:\"/registry/masterleases/162.253.55.242\" "} {"level":"info","ts":"2026-09-09T01:49:35.953215Z","caller":"traceutil/trace.go:171","msg":"trace[437807240] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:20626; }","duration":"265.122391ms","start":"2026-09-09T01:49:35.68808Z","end":"2026-09-09T01:49:35.953203Z","steps":["trace[437807240] 'agreement among raft nodes before linearized reading' (duration: 264.990908ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:49:35.953382Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T01:49:35.599938Z","time spent":"353.411696ms","remote":"127.0.0.1:51640","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-09T01:49:36.249259Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.147961ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:41"} {"level":"info","ts":"2026-09-09T01:49:36.24953Z","caller":"traceutil/trace.go:171","msg":"trace[1049682946] linearizableReadLoop","detail":"{readStateIndex:21336; appliedIndex:21334; }","duration":"116.231645ms","start":"2026-09-09T01:49:36.133281Z","end":"2026-09-09T01:49:36.249513Z","steps":["trace[1049682946] 'read index received' (duration: 109.060115ms)","trace[1049682946] 'applied index is now lower than readState.Index' (duration: 7.16988ms)"],"step_count":2} {"level":"warn","ts":"2026-09-09T01:49:36.249759Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.474642ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-09T01:49:36.249797Z","caller":"traceutil/trace.go:171","msg":"trace[1430765613] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:20627; }","duration":"116.564554ms","start":"2026-09-09T01:49:36.133222Z","end":"2026-09-09T01:49:36.249786Z","steps":["trace[1430765613] 'agreement among raft nodes before linearized reading' (duration: 116.348098ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:49:36.250142Z","caller":"traceutil/trace.go:171","msg":"trace[938367493] transaction","detail":"{read_only:false; response_revision:20627; number_of_response:1; }","duration":"291.657211ms","start":"2026-09-09T01:49:35.958452Z","end":"2026-09-09T01:49:36.250109Z","steps":["trace[938367493] 'process raft request' (duration: 290.938389ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:52:52.143374Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":19976} {"level":"info","ts":"2026-09-09T01:52:52.273949Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":19976,"took":"125.584756ms","hash":2894459534,"current-db-size-bytes":56176640,"current-db-size":"56 MB","current-db-size-in-use-bytes":29876224,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-09-09T01:52:52.27403Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2894459534,"revision":19976,"compact-revision":17519} {"level":"info","ts":"2026-09-09T01:57:52.151286Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":22238} {"level":"info","ts":"2026-09-09T01:57:52.276498Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":22238,"took":"120.568289ms","hash":3403023064,"current-db-size-bytes":56176640,"current-db-size":"56 MB","current-db-size-in-use-bytes":42127360,"current-db-size-in-use":"42 MB"} {"level":"info","ts":"2026-09-09T01:57:52.276587Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3403023064,"revision":22238,"compact-revision":19976} {"level":"info","ts":"2026-09-09T02:02:52.276856Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":25332} {"level":"warn","ts":"2026-09-09T02:02:52.277977Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.946049ms","expected-duration":"100ms","prefix":"","request":"header: compaction: ","response":"size:6"} {"level":"info","ts":"2026-09-09T02:02:52.278116Z","caller":"traceutil/trace.go:171","msg":"trace[1977651298] compact","detail":"{revision:25332; response_revision:27618; }","duration":"119.307087ms","start":"2026-09-09T02:02:52.158786Z","end":"2026-09-09T02:02:52.278094Z","steps":["trace[1977651298] 'check and update compact revision' (duration: 115.797195ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T02:02:52.663022Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":25332,"took":"379.567527ms","hash":3192170410,"current-db-size-bytes":56176640,"current-db-size":"56 MB","current-db-size-in-use-bytes":30957568,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-09-09T02:02:52.663084Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3192170410,"revision":25332,"compact-revision":22238}